builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0111 starttime: 1481716485.94 results: success (0) buildid: 20161214004021 builduid: 9fe8d74653e1433e8d233167a04cf4c1 revision: c7f071262fbc3862dd41c8bc7d89999e2926126b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:45.937191) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:45.937653) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:45.937928) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:45.962977) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:45.963384) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-14 03:54:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.14M=0.001s 2016-12-14 03:54:46 (8.14 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127851 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:46.114559) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:46.114867) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020268 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:46.162818) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-14 03:54:46.163186) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c7f071262fbc3862dd41c8bc7d89999e2926126b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c7f071262fbc3862dd41c8bc7d89999e2926126b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-14 03:54:46,230 truncating revision to first 12 chars 2016-12-14 03:54:46,230 Setting DEBUG logging. 2016-12-14 03:54:46,230 attempt 1/10 2016-12-14 03:54:46,230 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c7f071262fbc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-14 03:54:47,356 unpacking tar archive at: mozilla-aurora-c7f071262fbc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.398042 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-14 03:54:47.584895) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:47.585274) ========= script_repo_revision: c7f071262fbc3862dd41c8bc7d89999e2926126b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:47.585651) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:47.585939) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 03:54:47.606229) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-12-14 03:54:47.606713) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:54:47 INFO - MultiFileLogger online at 20161214 03:54:47 in /builds/slave/test 03:54:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:54:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:54:47 INFO - {'append_to_log': False, 03:54:47 INFO - 'base_work_dir': '/builds/slave/test', 03:54:47 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:54:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:54:47 INFO - 'branch': 'Mozilla-Aurora', 03:54:47 INFO - 'buildbot_json_path': 'buildprops.json', 03:54:47 INFO - 'config_files': ('talos/mac_config.py',), 03:54:47 INFO - 'default_actions': ('clobber', 03:54:47 INFO - 'read-buildbot-config', 03:54:47 INFO - 'download-and-extract', 03:54:47 INFO - 'populate-webroot', 03:54:47 INFO - 'create-virtualenv', 03:54:47 INFO - 'install', 03:54:47 INFO - 'run-tests'), 03:54:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:54:47 INFO - 'download_minidump_stackwalk': True, 03:54:47 INFO - 'download_symbols': 'ondemand', 03:54:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:54:47 INFO - 'tooltool.py': '/tools/tooltool.py', 03:54:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:54:47 INFO - '/tools/misc-python/virtualenv.py')}, 03:54:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:54:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:54:47 INFO - 'installer_path': 'installer.exe', 03:54:47 INFO - 'log_level': 'info', 03:54:47 INFO - 'log_name': 'talos', 03:54:47 INFO - 'log_to_console': True, 03:54:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:54:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:54:47 INFO - 'opt_config_files': (), 03:54:47 INFO - 'pip_index': False, 03:54:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:54:47 INFO - 'cmd': ('bash', 03:54:47 INFO - '-c', 03:54:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:54:47 INFO - 'enabled': True, 03:54:47 INFO - 'halt_on_failure': False, 03:54:47 INFO - 'name': 'check_screen_resolution'},), 03:54:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:54:47 INFO - 'cmd': ('bash', 03:54:47 INFO - '-c', 03:54:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:54:47 INFO - 'enabled': True, 03:54:47 INFO - 'halt_on_failure': False, 03:54:47 INFO - 'name': 'check_screen_resolution'},), 03:54:47 INFO - 'run_cmd_checks_enabled': True, 03:54:47 INFO - 'sps_profile': False, 03:54:47 INFO - 'sps_profile_interval': 0, 03:54:47 INFO - 'suite': 'dromaeojs-e10s', 03:54:47 INFO - 'system_bits': '32', 03:54:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:54:47 INFO - 'title': 't-yosemite-r7-0111', 03:54:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:54:47 INFO - 'use_talos_json': True, 03:54:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:54:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:54:47 INFO - 'work_dir': 'build'} 03:54:47 INFO - [mozharness: 2016-12-14 11:54:47.803938Z] Running clobber step. 03:54:47 INFO - Running pre-action listener: _resource_record_pre_action 03:54:47 INFO - Running main action method: clobber 03:54:47 INFO - rmtree: /builds/slave/test/build 03:54:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:54:49 INFO - Running post-action listener: _resource_record_post_action 03:54:49 INFO - [mozharness: 2016-12-14 11:54:49.412116Z] Finished clobber step (success) 03:54:49 INFO - [mozharness: 2016-12-14 11:54:49.412288Z] Running read-buildbot-config step. 03:54:49 INFO - Running pre-action listener: _resource_record_pre_action 03:54:49 INFO - Running main action method: read_buildbot_config 03:54:49 INFO - Using buildbot properties: 03:54:49 INFO - { 03:54:49 INFO - "project": "", 03:54:49 INFO - "product": "firefox", 03:54:49 INFO - "script_repo_revision": "production", 03:54:49 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 03:54:49 INFO - "repository": "", 03:54:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 03:54:49 INFO - "buildid": "20161214004021", 03:54:49 INFO - "stage_platform": "macosx64", 03:54:49 INFO - "basedir": "/builds/slave/test", 03:54:49 INFO - "nightly_build": "True", 03:54:49 INFO - "buildnumber": 55, 03:54:49 INFO - "slavename": "t-yosemite-r7-0111", 03:54:49 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:54:49 INFO - "platform": "yosemite_r7", 03:54:49 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:54:49 INFO - "branch": "mozilla-aurora", 03:54:49 INFO - "repo_path": "releases/mozilla-aurora", 03:54:49 INFO - "revision": "c7f071262fbc3862dd41c8bc7d89999e2926126b", 03:54:49 INFO - "pgo_build": "False", 03:54:49 INFO - "builduid": "9fe8d74653e1433e8d233167a04cf4c1", 03:54:49 INFO - "slavebuilddir": "test" 03:54:49 INFO - } 03:54:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:54:49 INFO - Running post-action listener: _resource_record_post_action 03:54:49 INFO - [mozharness: 2016-12-14 11:54:49.415780Z] Finished read-buildbot-config step (success) 03:54:49 INFO - [mozharness: 2016-12-14 11:54:49.415887Z] Running download-and-extract step. 03:54:49 INFO - Running pre-action listener: _resource_record_pre_action 03:54:49 INFO - Running main action method: download_and_extract 03:54:49 INFO - mkdir: /builds/slave/test/build/tests 03:54:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:54:49 INFO - trying https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:54:49 INFO - Downloading https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:54:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 03:54:50 INFO - Downloaded 1199 bytes. 03:54:50 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:54:50 INFO - Using the following test package requirements: 03:54:50 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:54:50 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:54:50 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:54:50 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:54:50 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:54:50 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:54:50 INFO - u'jsshell-mac.zip'], 03:54:50 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:54:50 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:54:50 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:54:50 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:54:50 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:54:50 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:54:50 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:54:50 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:54:50 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:54:50 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:54:50 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:54:50 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:54:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:54:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:54:50 INFO - Fetch https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:54:51 INFO - Http code: 200 03:54:51 INFO - via: 1.1 7db492e48a5d55351dcb787a8a14e113.cloudfront.net (CloudFront) 03:54:51 INFO - x-amz-cf-id: _p5xSWTYy8iAEy9uTAgf0F7UFeAFCIKsvEx8Oo-eZqbzSIzbPqrTeA== 03:54:51 INFO - x-amz-version-id: jgRemnb9GCrPDaNzlYy1Pza9zt3trXi8 03:54:51 INFO - Expected file size: 16281142 03:54:51 INFO - Obtained file size: 16281142 03:54:58 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:54:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:54:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:54:58 INFO - Fetch https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:54:58 INFO - Http code: 200 03:54:58 INFO - via: 1.1 fc7da6323a08a2c16d48dd4939ce0898.cloudfront.net (CloudFront) 03:54:58 INFO - x-amz-cf-id: zM5CYhZgBkAWLjG0147GJG1dA6HXzR8RlSru-CHbNhG0EdbpNbJpwg== 03:54:58 INFO - x-amz-version-id: jgRemnb9GCrPDaNzlYy1Pza9zt3trXi8 03:54:59 INFO - Expected file size: 16281142 03:54:59 INFO - Obtained file size: 16281142 03:55:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:55:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:55:05 INFO - Fetch https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:55:06 INFO - Http code: 200 03:55:06 INFO - via: 1.1 11512f00e812993b9daa35c376d7cb20.cloudfront.net (CloudFront) 03:55:06 INFO - x-amz-cf-id: De2ggOlXqTP5VDY1j1oSFY54HufYnR0F7jFSluPuvbi-VTupdnHbwA== 03:55:06 INFO - x-amz-version-id: FSC_shg6F92Ud8C4p5PwLhNIt66Ky_In 03:55:06 INFO - Expected file size: 14409038 03:55:06 INFO - Obtained file size: 14409038 03:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:06 INFO - trying https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:55:06 INFO - Downloading https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:55:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 03:55:09 INFO - Downloaded 109850234 bytes. 03:55:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:55:09 INFO - mkdir: /builds/slave/test/properties 03:55:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:55:09 INFO - Writing to file /builds/slave/test/properties/build_url 03:55:09 INFO - Contents: 03:55:09 INFO - build_url:https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:55:10 INFO - Running post-action listener: _resource_record_post_action 03:55:10 INFO - Running post-action listener: set_extra_try_arguments 03:55:10 INFO - [mozharness: 2016-12-14 11:55:10.547609Z] Finished download-and-extract step (success) 03:55:10 INFO - [mozharness: 2016-12-14 11:55:10.547723Z] Running populate-webroot step. 03:55:10 INFO - Running pre-action listener: _resource_record_pre_action 03:55:10 INFO - Running main action method: populate_webroot 03:55:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:55:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:55:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:55:10 INFO - '--tppagecycles', 03:55:10 INFO - '7'], 03:55:10 INFO - 'tests': ['tsvgm']}}, 03:55:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:55:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:55:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:55:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:55:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:55:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:55:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:55:10 INFO - 'talos_options': ['--disable-e10s'], 03:55:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:55:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:55:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:55:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:55:10 INFO - 'talos_options': ['--disable-e10s'], 03:55:10 INFO - 'tests': ['damp', 'tps']}, 03:55:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:55:10 INFO - 'tests': ['damp', 'tps']}, 03:55:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:55:10 INFO - 'tests': ['dromaeo_dom']}, 03:55:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:55:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:55:10 INFO - 'tests': ['basic_compositor_video']}, 03:55:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:55:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:55:10 INFO - 'tests': ['a11yr', 03:55:10 INFO - 'ts_paint', 03:55:10 INFO - 'tpaint', 03:55:10 INFO - 'sessionrestore', 03:55:10 INFO - 'sessionrestore_no_auto_restore', 03:55:10 INFO - 'tabpaint']}, 03:55:10 INFO - 'other-e10s': {'tests': ['a11yr', 03:55:10 INFO - 'ts_paint', 03:55:10 INFO - 'tpaint', 03:55:10 INFO - 'sessionrestore', 03:55:10 INFO - 'sessionrestore_no_auto_restore', 03:55:10 INFO - 'tabpaint']}, 03:55:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:55:10 INFO - 'ts_paint', 03:55:10 INFO - 'tpaint', 03:55:10 INFO - 'sessionrestore', 03:55:10 INFO - 'sessionrestore_no_auto_restore', 03:55:10 INFO - 'tabpaint']}, 03:55:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:55:10 INFO - 'ts_paint', 03:55:10 INFO - 'tpaint', 03:55:10 INFO - 'sessionrestore', 03:55:10 INFO - 'sessionrestore_no_auto_restore', 03:55:10 INFO - 'tabpaint']}, 03:55:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:55:10 INFO - 'tests': ['a11yr', 03:55:10 INFO - 'ts_paint', 03:55:10 INFO - 'tpaint', 03:55:10 INFO - 'sessionrestore', 03:55:10 INFO - 'sessionrestore_no_auto_restore', 03:55:10 INFO - 'tabpaint']}, 03:55:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:55:10 INFO - 'tests': ['a11yr', 03:55:10 INFO - 'ts_paint', 03:55:10 INFO - 'tpaint', 03:55:10 INFO - 'sessionrestore', 03:55:10 INFO - 'sessionrestore_no_auto_restore', 03:55:10 INFO - 'tabpaint']}, 03:55:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:55:10 INFO - 'tests': ['tsvgx', 03:55:10 INFO - 'tsvgr_opacity', 03:55:10 INFO - 'tart', 03:55:10 INFO - 'tscrollx', 03:55:10 INFO - 'cart']}, 03:55:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:55:10 INFO - 'tsvgr_opacity', 03:55:10 INFO - 'tart', 03:55:10 INFO - 'tscrollx', 03:55:10 INFO - 'cart']}, 03:55:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:55:10 INFO - 'talos_options': ['--disable-e10s'], 03:55:10 INFO - 'tests': ['tp5o']}, 03:55:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:55:10 INFO - 'tests': ['tp5o']}, 03:55:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:55:10 INFO - 'talos_options': ['--disable-e10s', 03:55:10 INFO - '--xperf_path', 03:55:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:55:10 INFO - 'tests': ['tp5n']}, 03:55:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:55:10 INFO - 'talos_options': ['--xperf_path', 03:55:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:55:10 INFO - 'tests': ['tp5n']}}, 03:55:10 INFO - 'talos.zip': {'path': '', 03:55:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:55:10 INFO - Running post-action listener: _resource_record_post_action 03:55:10 INFO - [mozharness: 2016-12-14 11:55:10.566548Z] Finished populate-webroot step (success) 03:55:10 INFO - [mozharness: 2016-12-14 11:55:10.566657Z] Running create-virtualenv step. 03:55:10 INFO - Running pre-action listener: _resource_record_pre_action 03:55:10 INFO - Running main action method: create_virtualenv 03:55:10 INFO - Creating virtualenv /builds/slave/test/build/venv 03:55:10 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:55:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:55:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:55:10 INFO - Using real prefix '/tools/python27' 03:55:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:55:11 INFO - Installing distribute.............................................................................................................................................................................................done. 03:55:14 INFO - Installing pip.................done. 03:55:14 INFO - Return code: 0 03:55:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:55:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:55:14 INFO - Reading from file tmpfile_stdout 03:55:14 INFO - Output received: 03:55:14 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 03:55:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:55:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10acbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5094d9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad3d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a939db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacbb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:55:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:55:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:55:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 03:55:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:14 INFO - 'HOME': '/Users/cltbld', 03:55:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:14 INFO - 'LOGNAME': 'cltbld', 03:55:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:14 INFO - 'MOZ_NO_REMOTE': '1', 03:55:14 INFO - 'NO_EM_RESTART': '1', 03:55:14 INFO - 'PAGER': '/bin/cat', 03:55:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:14 INFO - 'PWD': '/builds/slave/test', 03:55:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:14 INFO - 'SHELL': '/bin/bash', 03:55:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 03:55:14 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:55:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:14 INFO - 'USER': 'cltbld', 03:55:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:14 INFO - 'XPC_FLAGS': '0x0', 03:55:14 INFO - 'XPC_SERVICE_NAME': '0', 03:55:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:14 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:55:14 INFO - Cleaning up... 03:55:14 INFO - Return code: 0 03:55:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:55:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10acbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5094d9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad3d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a939db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacbb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:55:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:55:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:55:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 03:55:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:14 INFO - 'HOME': '/Users/cltbld', 03:55:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:14 INFO - 'LOGNAME': 'cltbld', 03:55:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:14 INFO - 'MOZ_NO_REMOTE': '1', 03:55:14 INFO - 'NO_EM_RESTART': '1', 03:55:14 INFO - 'PAGER': '/bin/cat', 03:55:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:14 INFO - 'PWD': '/builds/slave/test', 03:55:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:14 INFO - 'SHELL': '/bin/bash', 03:55:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 03:55:14 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:55:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:14 INFO - 'USER': 'cltbld', 03:55:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:14 INFO - 'XPC_FLAGS': '0x0', 03:55:14 INFO - 'XPC_SERVICE_NAME': '0', 03:55:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:15 INFO - Downloading/unpacking psutil>=3.1.1 03:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:55:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:55:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:55:18 INFO - Installing collected packages: psutil 03:55:18 INFO - Running setup.py install for psutil 03:55:18 INFO - building 'psutil._psutil_osx' extension 03:55:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:55:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:55:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:55:19 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:55:19 INFO - building 'psutil._psutil_posix' extension 03:55:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:55:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:55:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:55:19 INFO - ^ 03:55:19 INFO - 1 warning generated. 03:55:19 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:55:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:55:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:55:19 INFO - Successfully installed psutil 03:55:19 INFO - Cleaning up... 03:55:19 INFO - Return code: 0 03:55:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:55:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10acbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5094d9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad3d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a939db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacbb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:55:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:55:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:55:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 03:55:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:19 INFO - 'HOME': '/Users/cltbld', 03:55:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:19 INFO - 'LOGNAME': 'cltbld', 03:55:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:19 INFO - 'MOZ_NO_REMOTE': '1', 03:55:19 INFO - 'NO_EM_RESTART': '1', 03:55:19 INFO - 'PAGER': '/bin/cat', 03:55:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:19 INFO - 'PWD': '/builds/slave/test', 03:55:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:19 INFO - 'SHELL': '/bin/bash', 03:55:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 03:55:19 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:55:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:19 INFO - 'USER': 'cltbld', 03:55:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:19 INFO - 'XPC_FLAGS': '0x0', 03:55:19 INFO - 'XPC_SERVICE_NAME': '0', 03:55:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:55:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:55:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:55:22 INFO - Installing collected packages: mozsystemmonitor 03:55:22 INFO - Running setup.py install for mozsystemmonitor 03:55:22 INFO - Successfully installed mozsystemmonitor 03:55:22 INFO - Cleaning up... 03:55:22 INFO - Return code: 0 03:55:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10acbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5094d9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad3d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a939db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacbb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:55:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:55:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:55:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 03:55:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:22 INFO - 'HOME': '/Users/cltbld', 03:55:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:22 INFO - 'LOGNAME': 'cltbld', 03:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:22 INFO - 'MOZ_NO_REMOTE': '1', 03:55:22 INFO - 'NO_EM_RESTART': '1', 03:55:22 INFO - 'PAGER': '/bin/cat', 03:55:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:22 INFO - 'PWD': '/builds/slave/test', 03:55:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:22 INFO - 'SHELL': '/bin/bash', 03:55:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 03:55:22 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:55:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:22 INFO - 'USER': 'cltbld', 03:55:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:22 INFO - 'XPC_FLAGS': '0x0', 03:55:22 INFO - 'XPC_SERVICE_NAME': '0', 03:55:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:22 INFO - Downloading/unpacking jsonschema==2.5.1 03:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:55:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:25 INFO - Downloading functools32-3.2.3-2.tar.gz 03:55:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:55:25 INFO - warning: no files found matching '*.txt' 03:55:25 INFO - no previously-included directories found matching 'build' 03:55:25 INFO - no previously-included directories found matching 'dist' 03:55:25 INFO - no previously-included directories found matching '.git*' 03:55:25 INFO - Installing collected packages: jsonschema, functools32 03:55:25 INFO - Running setup.py install for functools32 03:55:26 INFO - warning: no files found matching '*.txt' 03:55:26 INFO - no previously-included directories found matching 'build' 03:55:26 INFO - no previously-included directories found matching 'dist' 03:55:26 INFO - no previously-included directories found matching '.git*' 03:55:26 INFO - Successfully installed jsonschema functools32 03:55:26 INFO - Cleaning up... 03:55:26 INFO - Return code: 0 03:55:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:55:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10acbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5094d9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad3d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a939db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacbb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:55:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:55:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:55:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 03:55:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:26 INFO - 'HOME': '/Users/cltbld', 03:55:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:26 INFO - 'LOGNAME': 'cltbld', 03:55:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:26 INFO - 'MOZ_NO_REMOTE': '1', 03:55:26 INFO - 'NO_EM_RESTART': '1', 03:55:26 INFO - 'PAGER': '/bin/cat', 03:55:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:26 INFO - 'PWD': '/builds/slave/test', 03:55:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:26 INFO - 'SHELL': '/bin/bash', 03:55:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 03:55:26 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:55:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:26 INFO - 'USER': 'cltbld', 03:55:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:26 INFO - 'XPC_FLAGS': '0x0', 03:55:26 INFO - 'XPC_SERVICE_NAME': '0', 03:55:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:55:26 INFO - Cleaning up... 03:55:26 INFO - Return code: 0 03:55:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:55:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10acbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5094d9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad3d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a939db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacbb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:55:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:55:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:55:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 03:55:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:26 INFO - 'HOME': '/Users/cltbld', 03:55:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:26 INFO - 'LOGNAME': 'cltbld', 03:55:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:26 INFO - 'MOZ_NO_REMOTE': '1', 03:55:26 INFO - 'NO_EM_RESTART': '1', 03:55:26 INFO - 'PAGER': '/bin/cat', 03:55:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:26 INFO - 'PWD': '/builds/slave/test', 03:55:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:26 INFO - 'SHELL': '/bin/bash', 03:55:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 03:55:26 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:55:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:26 INFO - 'USER': 'cltbld', 03:55:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:26 INFO - 'XPC_FLAGS': '0x0', 03:55:26 INFO - 'XPC_SERVICE_NAME': '0', 03:55:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:26 INFO - Downloading/unpacking blobuploader==1.2.4 03:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:29 INFO - Downloading blobuploader-1.2.4.tar.gz 03:55:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:55:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:55:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:55:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:30 INFO - Downloading docopt-0.6.1.tar.gz 03:55:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:55:30 INFO - Installing collected packages: blobuploader, requests, docopt 03:55:30 INFO - Running setup.py install for blobuploader 03:55:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:55:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:55:30 INFO - Running setup.py install for requests 03:55:31 INFO - Running setup.py install for docopt 03:55:31 INFO - Successfully installed blobuploader requests docopt 03:55:31 INFO - Cleaning up... 03:55:31 INFO - Return code: 0 03:55:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10acbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5094d9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad3d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a939db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacbb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:55:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:55:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:55:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 03:55:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:31 INFO - 'HOME': '/Users/cltbld', 03:55:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:31 INFO - 'LOGNAME': 'cltbld', 03:55:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:31 INFO - 'MOZ_NO_REMOTE': '1', 03:55:31 INFO - 'NO_EM_RESTART': '1', 03:55:31 INFO - 'PAGER': '/bin/cat', 03:55:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:31 INFO - 'PWD': '/builds/slave/test', 03:55:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:31 INFO - 'SHELL': '/bin/bash', 03:55:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 03:55:31 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:55:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:31 INFO - 'USER': 'cltbld', 03:55:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:31 INFO - 'XPC_FLAGS': '0x0', 03:55:31 INFO - 'XPC_SERVICE_NAME': '0', 03:55:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:55:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-EyP9d1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:55:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:55:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-lkvVc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:55:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:55:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-fL8aXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:55:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:55:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-GVz1ma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:55:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-p1atxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:55:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-2zXcLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:55:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-gWpLYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:55:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-wa7wYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:55:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-twEFR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:55:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-ByJ8lF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:55:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-KYiwm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:55:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-0b54Ic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:55:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-qC0W1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:55:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-I6hj9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:33 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-bK86py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:55:33 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_pm7bH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:55:33 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-xP2uue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:55:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:55:33 INFO - Running setup.py install for manifestparser 03:55:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:55:33 INFO - Running setup.py install for mozcrash 03:55:33 INFO - Running setup.py install for mozdebug 03:55:33 INFO - Running setup.py install for mozdevice 03:55:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:55:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:55:34 INFO - Running setup.py install for mozfile 03:55:34 INFO - Running setup.py install for mozhttpd 03:55:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:55:34 INFO - Running setup.py install for mozinfo 03:55:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:55:34 INFO - Running setup.py install for mozInstall 03:55:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:55:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:55:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:55:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:55:34 INFO - Running setup.py install for mozleak 03:55:34 INFO - Running setup.py install for mozlog 03:55:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:55:34 INFO - Running setup.py install for moznetwork 03:55:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:55:35 INFO - Running setup.py install for mozprocess 03:55:35 INFO - Running setup.py install for mozprofile 03:55:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:55:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:55:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:55:35 INFO - Running setup.py install for mozrunner 03:55:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:55:35 INFO - Running setup.py install for mozscreenshot 03:55:35 INFO - Running setup.py install for moztest 03:55:35 INFO - Running setup.py install for mozversion 03:55:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:55:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:55:35 INFO - Cleaning up... 03:55:35 INFO - Return code: 0 03:55:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:55:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10acbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5094d9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad3d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a939db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacbb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:55:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:55:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:55:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 03:55:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:36 INFO - 'HOME': '/Users/cltbld', 03:55:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:36 INFO - 'LOGNAME': 'cltbld', 03:55:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:36 INFO - 'MOZ_NO_REMOTE': '1', 03:55:36 INFO - 'NO_EM_RESTART': '1', 03:55:36 INFO - 'PAGER': '/bin/cat', 03:55:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:36 INFO - 'PWD': '/builds/slave/test', 03:55:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:36 INFO - 'SHELL': '/bin/bash', 03:55:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 03:55:36 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:55:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:36 INFO - 'USER': 'cltbld', 03:55:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:36 INFO - 'XPC_FLAGS': '0x0', 03:55:36 INFO - 'XPC_SERVICE_NAME': '0', 03:55:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:55:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-SonLS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:55:36 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:55:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-qPoGTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:55:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:55:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-yWmIVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:55:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:55:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-zkYSh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:55:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:55:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-p8TsLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:55:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:55:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-X89eLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:55:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:55:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-sLMkcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:55:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-SqwZId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:55:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-8SY_Am-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:55:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-VvQMW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:55:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-L4va7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:55:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-z99LIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:55:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-1SErvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:55:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-guKm9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-wDpAmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:55:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-R0JWBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:55:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-PAYQS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:55:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:55:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:55:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:55:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:55:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:55:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:55:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:40 INFO - Downloading blessings-1.6.tar.gz 03:55:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:55:40 INFO - Installing collected packages: blessings 03:55:40 INFO - Running setup.py install for blessings 03:55:40 INFO - Successfully installed blessings 03:55:40 INFO - Cleaning up... 03:55:41 INFO - Return code: 0 03:55:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:55:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:55:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:55:41 INFO - Reading from file tmpfile_stdout 03:55:41 INFO - Current package versions: 03:55:41 INFO - blessings == 1.6 03:55:41 INFO - blobuploader == 1.2.4 03:55:41 INFO - docopt == 0.6.1 03:55:41 INFO - functools32 == 3.2.3-2 03:55:41 INFO - jsonschema == 2.5.1 03:55:41 INFO - manifestparser == 1.1 03:55:41 INFO - mozInstall == 1.12 03:55:41 INFO - mozcrash == 1.0 03:55:41 INFO - mozdebug == 0.1 03:55:41 INFO - mozdevice == 0.48 03:55:41 INFO - mozfile == 1.2 03:55:41 INFO - mozhttpd == 0.7 03:55:41 INFO - mozinfo == 0.9 03:55:41 INFO - mozleak == 0.1 03:55:41 INFO - mozlog == 3.4 03:55:41 INFO - moznetwork == 0.27 03:55:41 INFO - mozprocess == 0.23 03:55:41 INFO - mozprofile == 0.28 03:55:41 INFO - mozrunner == 6.13 03:55:41 INFO - mozscreenshot == 0.1 03:55:41 INFO - mozsystemmonitor == 0.3 03:55:41 INFO - moztest == 0.7 03:55:41 INFO - mozversion == 1.4 03:55:41 INFO - psutil == 3.1.1 03:55:41 INFO - requests == 1.2.3 03:55:41 INFO - wsgiref == 0.1.2 03:55:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:55:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10acbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5094d9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad3d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a939db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacbb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:55:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:55:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:55:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 03:55:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:41 INFO - 'HOME': '/Users/cltbld', 03:55:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:41 INFO - 'LOGNAME': 'cltbld', 03:55:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:41 INFO - 'MOZ_NO_REMOTE': '1', 03:55:41 INFO - 'NO_EM_RESTART': '1', 03:55:41 INFO - 'PAGER': '/bin/cat', 03:55:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:41 INFO - 'PWD': '/builds/slave/test', 03:55:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:41 INFO - 'SHELL': '/bin/bash', 03:55:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 03:55:41 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:55:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:41 INFO - 'USER': 'cltbld', 03:55:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:41 INFO - 'XPC_FLAGS': '0x0', 03:55:41 INFO - 'XPC_SERVICE_NAME': '0', 03:55:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:55:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:55:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:55:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:55:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:55:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:55:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:55:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:55:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:55:41 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:55:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:55:41 INFO - Cleaning up... 03:55:41 INFO - Return code: 0 03:55:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:55:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:55:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10acbf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac35570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5094d9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad3d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a939db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacbb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:55:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:55:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:55:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 03:55:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:41 INFO - 'HOME': '/Users/cltbld', 03:55:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:41 INFO - 'LOGNAME': 'cltbld', 03:55:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:41 INFO - 'MOZ_NO_REMOTE': '1', 03:55:41 INFO - 'NO_EM_RESTART': '1', 03:55:41 INFO - 'PAGER': '/bin/cat', 03:55:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:41 INFO - 'PWD': '/builds/slave/test', 03:55:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:41 INFO - 'SHELL': '/bin/bash', 03:55:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 03:55:41 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:55:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:41 INFO - 'USER': 'cltbld', 03:55:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:41 INFO - 'XPC_FLAGS': '0x0', 03:55:41 INFO - 'XPC_SERVICE_NAME': '0', 03:55:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:55:41 INFO - Cleaning up... 03:55:41 INFO - Return code: 0 03:55:41 INFO - Running post-action listener: _resource_record_post_action 03:55:41 INFO - Running post-action listener: _start_resource_monitoring 03:55:41 INFO - Starting resource monitoring. 03:55:41 INFO - [mozharness: 2016-12-14 11:55:41.884871Z] Finished create-virtualenv step (success) 03:55:41 INFO - [mozharness: 2016-12-14 11:55:41.885876Z] Running install step. 03:55:41 INFO - Running pre-action listener: _resource_record_pre_action 03:55:41 INFO - Running main action method: install 03:55:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:55:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:55:42 INFO - Reading from file tmpfile_stdout 03:55:42 INFO - Detecting whether we're running mozinstall >=1.0... 03:55:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:55:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:55:42 INFO - Reading from file tmpfile_stdout 03:55:42 INFO - Output received: 03:55:42 INFO - Usage: mozinstall [options] installer 03:55:42 INFO - Options: 03:55:42 INFO - -h, --help show this help message and exit 03:55:42 INFO - -d DEST, --destination=DEST 03:55:42 INFO - Directory to install application into. [default: 03:55:42 INFO - "/builds/slave/test"] 03:55:42 INFO - --app=APP Application being installed. [default: firefox] 03:55:42 INFO - mkdir: /builds/slave/test/build/application 03:55:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:55:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:56:13 INFO - Reading from file tmpfile_stdout 03:56:13 INFO - Output received: 03:56:13 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:56:13 INFO - Running post-action listener: _resource_record_post_action 03:56:13 INFO - [mozharness: 2016-12-14 11:56:13.236952Z] Finished install step (success) 03:56:13 INFO - [mozharness: 2016-12-14 11:56:13.237092Z] Running run-tests step. 03:56:13 INFO - Running pre-action listener: _resource_record_pre_action 03:56:13 INFO - Running main action method: run_tests 03:56:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:56:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:56:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:56:13 INFO - 2016-12-14 03:56:13.251 screenresolution[1927:18728] starting screenresolution argv=screenresolution get 03:56:13 INFO - 2016-12-14 03:56:13.254 screenresolution[1927:18728] Display 0: 1600x1200x32@60 03:56:13 INFO - 2016-12-14 03:56:13.260 screenresolution[1928:18730] starting screenresolution argv=screenresolution list 03:56:13 INFO - Available Modes on Display 0 03:56:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:56:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:56:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:56:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:56:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:56:13 INFO - Intel Iris: 03:56:13 INFO - Chipset Model: Intel Iris 03:56:13 INFO - Type: GPU 03:56:13 INFO - Bus: Built-In 03:56:13 INFO - VRAM (Dynamic, Max): 1536 MB 03:56:13 INFO - Vendor: Intel (0x8086) 03:56:13 INFO - Device ID: 0x0a2e 03:56:13 INFO - Revision ID: 0x0009 03:56:13 INFO - Displays: 03:56:13 INFO - Display: 03:56:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:56:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:56:13 INFO - Display Serial Number: 1600x1200 60 03:56:13 INFO - Main Display: Yes 03:56:13 INFO - Mirror: Off 03:56:13 INFO - Online: Yes 03:56:13 INFO - Rotation: Supported 03:56:13 INFO - Return code: 0 03:56:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:56:13 INFO - Python 2.7.3 03:56:13 INFO - Return code: 0 03:56:13 INFO - grabbing minidump binary from tooltool 03:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:13 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad3d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a939db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:56:13 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:56:13 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:56:13 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:56:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:56:13 INFO - Return code: 0 03:56:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:56:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:56:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:56:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:56:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:56:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0111 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:56:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render', 03:56:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:13 INFO - 'HOME': '/Users/cltbld', 03:56:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:13 INFO - 'LOGNAME': 'cltbld', 03:56:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:56:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:13 INFO - 'MOZ_NO_REMOTE': '1', 03:56:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:56:13 INFO - 'NO_EM_RESTART': '1', 03:56:13 INFO - 'PAGER': '/bin/cat', 03:56:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:13 INFO - 'PWD': '/builds/slave/test', 03:56:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:56:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:13 INFO - 'SHELL': '/bin/bash', 03:56:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners', 03:56:13 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:56:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:13 INFO - 'USER': 'cltbld', 03:56:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:13 INFO - 'XPC_FLAGS': '0x0', 03:56:13 INFO - 'XPC_SERVICE_NAME': '0', 03:56:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:56:13 INFO - mozversion application_buildid: 20161214004021 03:56:13 INFO - mozversion application_changeset: c7f071262fbc3862dd41c8bc7d89999e2926126b 03:56:13 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:56:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:56:13 INFO - mozversion application_name: Firefox 03:56:13 INFO - mozversion application_remotingname: firefox-dev 03:56:13 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:56:13 INFO - mozversion application_vendor: Mozilla 03:56:13 INFO - mozversion application_version: 52.0a2 03:56:13 INFO - mozversion platform_buildid: 20161214004021 03:56:13 INFO - mozversion platform_changeset: c7f071262fbc3862dd41c8bc7d89999e2926126b 03:56:13 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:56:13 INFO - mozversion platform_version: 52.0a2 03:56:13 INFO - using testdate: 1481716573 03:56:13 INFO - actual date: 1481716573 03:56:13 INFO - starting webserver on 'localhost:54566' 03:56:13 INFO - SUITE-START | Running 2 tests 03:56:13 INFO - TEST-START | dromaeo_css 03:56:13 INFO - operating with platform_type : mac_ 03:56:13 INFO - Initialising browser for dromaeo_css test... 03:56:13 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpIWcl4R/profile http://localhost:54566/getInfo.html) 03:56:17 INFO - PROCESS | 1938 | 2016-12-14 03:56:17.008 plugin-container[1941:18905] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c3f, name = 'com.apple.tsm.portname' 03:56:17 INFO - PROCESS | 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:56:17 INFO - PROCESS | 1938 | 2016-12-14 03:56:17.008 plugin-container[1941:18905] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 03:56:17 INFO - PROCESS | 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:56:17 INFO - PROCESS | 1938 | 2016-12-14 03:56:17.008 plugin-container[1941:18905] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:56:17 INFO - PROCESS | 1938 | __metrics Screen width/height:1600/1200 03:56:17 INFO - PROCESS | 1938 | colorDepth:24 03:56:17 INFO - PROCESS | 1938 | Browser inner width/height: 1024/689 03:56:17 INFO - PROCESS | 1938 | __metrics 03:56:17 ERROR - PROCESS | 1938 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 03:56:17 INFO - TEST-INFO | 1938: exit 0 03:56:17 INFO - Browser initialized. 03:56:17 INFO - Running cycle 1/1 for dromaeo_css test... 03:56:17 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpIWcl4R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:56:20 INFO - PROCESS | 1942 | 2016-12-14 03:56:20.540 plugin-container[1943:19034] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843b, name = 'com.apple.tsm.portname' 03:56:20 INFO - PROCESS | 1942 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:56:20 INFO - PROCESS | 1942 | 2016-12-14 03:56:20.540 plugin-container[1943:19034] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8913, name = 'com.apple.CFPasteboardClient' 03:56:20 INFO - PROCESS | 1942 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:56:20 INFO - PROCESS | 1942 | 2016-12-14 03:56:20.541 plugin-container[1943:19034] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:56:30 INFO - PROCESS | 1942 | RSS: Main: 229609472 03:56:30 INFO - PROCESS | 1942 | 03:57:19 INFO - PROCESS | 1942 | 1481716639859 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:59:34 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:54566/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54566/tests/dromaeo/cssquery-ext.html) 03:59:35 INFO - PROCESS | 1942 | RSS: Main: 232505344 03:59:35 INFO - PROCESS | 1942 | 04:02:38 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:54566/tests/dromaeo/cssquery-ext.html (next: http://localhost:54566/tests/dromaeo/cssquery-jquery.html) 04:02:38 INFO - PROCESS | 1942 | RSS: Main: 243576832 04:02:38 INFO - PROCESS | 1942 | 04:05:40 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:54566/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54566/tests/dromaeo/cssquery-mootools.html) 04:05:41 INFO - PROCESS | 1942 | RSS: Main: 246804480 04:05:41 INFO - PROCESS | 1942 | 04:08:43 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:54566/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54566/tests/dromaeo/cssquery-prototype.html) 04:08:43 INFO - PROCESS | 1942 | RSS: Main: 250253312 04:08:43 INFO - PROCESS | 1942 | 04:11:46 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:54566/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54566/tests/dromaeo/cssquery-yui.html) 04:11:47 INFO - PROCESS | 1942 | RSS: Main: 253259776 04:11:47 INFO - PROCESS | 1942 | 04:14:49 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:54566/tests/dromaeo/cssquery-yui.html (next: http://localhost:54566/tests/dromaeo/cssquery-dojo.html) 04:14:50 INFO - PROCESS | 1942 | RSS: Main: 254902272 04:14:50 INFO - PROCESS | 1942 | 04:14:50 INFO - PROCESS | 1942 | __start_tp_report 04:14:50 INFO - PROCESS | 1942 | _x_x_mozilla_page_load 04:14:50 INFO - PROCESS | 1942 | _x_x_mozilla_page_load_details 04:14:50 INFO - PROCESS | 1942 | |i|pagename|runs| 04:14:50 INFO - PROCESS | 1942 | |0;dojo.html;3353.564036675232;3357.446021406468;3360.4119279126153;3382.915427114322;3395.2360718838263;4505.819767209307;5568.638038527498;5600.383957764642;5603.523700485453;5613.270274864267;5609.859753506164;5633.7183140843;5665.603407761459;5670.064439367509;5675.148727690838;5173.456787037363;5211.296474975868;5218.243354713583;5250.894982100355;5280.287161233242;5245.527902488775;5256.26412302278;5273.077211487994;5275.103232450473;5304.946950530503;5132.076226279269;5136.614753893455;5256.369235691723;5276.445973172819;5312.521873031426;5127.461616530248;5175.637705360588;5192.662476938987;5212.869678258036;5227.189785583221;5075.3402057732255;5187.636865419422;5218.712970786605;5275.287836142148;5296.443873393316;5652.886942261173;5726.713664316809;5745.396733343023;5758.107493338539;5781.046127388976;5996.940030599681;6076.544259180535;6109.847253818646;6120.693965301762;6133.969330153321;5108.948910510921;6105;6119.143319935212;6125.876495711611;6134.161069136565;5696.914546281768;5704.771809127671;5716.5998380113;5725.255716756795;5728.914066288968;6225.750969961241;6229.252489701174;6262.3111457739615;6278.4035516625845;6303.968480157661;4896.681715688469;5881.735321910524;6016.69916504173;6031.517478601701;6100.908486372708;6168.722407491674;6179.412955769195;6205.131281620576;6215.192025036807;6226.533010024266;6122.357152498924;6147.508199344042;6170.043643235305;6172.043333283348;6176.289726681399;5228.816991405282;5934.643921364645;5996.190514280516;6126.36500017443;6208.534359923024;4529.230030894756;4681.625469962395;4694.483606803249;4703.270992996091;4742.33607294979;4192.245395828719;4195.475565554306;4203.495580530356;4225.323948168279;4254.085371645111;5190.6107041972;5575.916361254584;5625.071863142535;5643.181738647955;5651.0675738503505;6095.664738439428;6117.4620395853735;6119.114362379921;6130.581587307925;6154.353792851686;5760.596758226968;5851.912221316684;5907.734151963172;5977.940220597825;5986.341502434729;5113.693178409308;5140.665856719302;5287.656302340336;5304.602154838402;5333.093374126331;6261.843453913598;6353.396427339396;6384.32964538726;6390.584612000205;6392.552521323462;6075.939240607538;6129.1112788644505;6138.877222455437;6215.316315205233;6252.531060170596;6232.688365581612;6241.282252541016;6292.307846137013;6301.936980630135;6397.360263973565;6093.086037094471;6098.237720284965;6121.081837724377;6134.907976380268;6150.231221097363;4893.657443978887;6206.348333424925;6213.937860621517;6215.875682486416;6239.844003899939;5847.093700476433;5884.882302354014;5914.58597898143;5988.610740301867;6022.307434645072;6012.3386427493815;6016.308124565731;6084.269887613515;6121.204243448323;6139.815805525842;5593.161025846156;5839.532837373085;5890.381509941566;5950.137230101697;5982.042873140276;5861.062230043172;5936.436038576328;6056.091586262096;6073.605215660968;6090.390960904051;5811.738471768866;5847.532197424111;5869.647821130746;5877.441643043904;5883.294004719461 04:14:50 INFO - PROCESS | 1942 | |1;ext.html;481.8506263058452;492.33534728117024;514.9880484847275;522.7281813457;528.9444608316126;32689.836550817214;39581.80209098954;40989.3851592226;41239.38140927881;41280.55518056872;42291.94270143248;42596.57403425973;42799.57200427995;42859.57140428595;43533.782331088376;8100.270975612194;9275.48984805837;9425.434473931586;9439.914409842853;9457.81084378312;9282.628694852197;9352.392094513869;9371.718848434557;9444.48055356955;9474.81050378992;8224.383171262149;9375.718728438123;9442;9467.905320946771;9468.431894086343;9359.95320023399;9428.434293942317;9440.29197810167;9440.527973601327;9463.195628371597;9301.953490232505;9347.532623368805;9380.577873995619;9387.530623468867;9394.765130871714;39618;40527.59472405297;40623.39064914029;40764;40843.18313633741;3341.331733653279;3350.363430948112;3549.2191717822298;3831.3295173344554;3960.1485680578526;29979.550306745416;30457.08628741119;30640.387192256254;30799;31163.53254701181;3104.8447577621027;3212.550242965987;3242.837858107085;3275.54142420059;3354.9832250838836;497761;502543;502672.4866375681;505229;506401;51656.96686066258;51718;51807.740961295705;51881.96236075333;51978.74010629923;63811;63817.68091159515;63980;64483;64757.70484590376;16036;16283.59291017734;16477.670446591;16496.75254871178;16527.33890644385;12619;12645.747085058247;12681;12681.49274029047;12717.809232861515;17419.82580174182;17758.644827103384;17794;17858.46424607264;17861.821381786016;5535.861603459945;5548.250986116904;6494.740210391626;6605.735770569124;6668.466522678271;849.8767678686556;855.5422848776;923.8290916180528;962.6245764151986;1092.6831218946575;30347.544786828214;30372.392552149275;30374.69625303763;30705.692943070284;30774.53838192429;890.7440508882445;924.3437159616748;1039.3919557059055;1042.9426381549088;1047.6490375724218;15143.621409464851;15447.691046179014;15791.973521721051;15815.68368632644;15952.2821473034;601045;642232.5776742266;671031;671732;673612;58640;58686.11970820441;58723.119153212734;58779;58903;1370.205280937054;1384.9889580606014;1390.5341710526884;1393.540131756518;1396;29431;29627.259318517208;30210.54684179695;30237.244068898453;30911.536326954665;52409.47590524046;52655.47344526506;52694.946101076996;52724;52797.472025279254;499680;499802;499944.5002774963;501490;502238;21316.680249795954;25429.10998115057;25546.23361299164;25802.09692660748;27051.323716907234;46663;46776.7661161692;48058;48106.75946620244;48332;39235.60764392434;39535;40140.79929600333;40196;40779.38830917479;41963.37054944239;42084.57915420806;42610.78694606507;43345;44919.29009190601;34588;35178.47232291569;38584.80707596444;39272.60727392689;40484.19031619292;37791.05522361963;41242.58757412388;41360.37959430551;42022.78988605038;42329;30414.84792576023;31512.684873150974;31650;31819.522707158947;32078.556464958667 04:14:50 INFO - PROCESS | 1942 | |2;jquery.html;372.7167352811863;378.7840930669519;388.05315031323585;388.3514530733675;405.17142443702625;17787.64424711505;18474.99999999998;20617.690734638993;20774.753514789125;20873.478163045933;17490.56273593161;20298.391048268575;20302.39092827214;20304.593908121828;20814.375568732925;4652.697574657654;4807.399075115611;5625.1843482694985;5800.158976948361;5832.387599302055;4901.901961960759;5683.772649094052;5805.303363596353;5813.157092221625;5829.9708501457435;5442.428545002777;5700.857478563048;5755.309362876461;5757.107648314518;5788.5369170466265;5768.884622307552;5818.912716309238;5824.883502329951;5831.154482600042;5833;5002.099622068026;5007.474215207387;5037.143685573461;5049.091163590552;5124.615653825978;19576.51058723529;20077.79922200774;24859;25110.748892511023;25199.748002519922;2266.3427605994243;2296.1045192374986;2494.1519883239785;2592.324763733394;2779.722027797224;13426.657334266592;14411;15569.299381527746;15894.52316430509;15993;1860.1071485686812;1872.0826794870552;1877.6244751049708;1903.2101677803832;1947.20164732459;93635;102975;114616;118232;119110;10731.356118632752;10940.45297735126;11055.22613417053;11075.50160242791;11193.328400296008;11223.494942727597;13420.194788312732;13686.794698079537;13753.724925501434;13764.93117534406;8335.708250211335;8612.224899759052;8658.350623703247;8752.299816014704;8783.5169065702;7266.63666816657;8649.481031138152;8702.608382622797;8713.825723485621;8888.24449921751;5253.763580638804;6771.28901465339;6783.660816959231;6818.352256535622;6860.5540639858245;1847.3072597775833;1882.9246830126642;1884.0500082414635;1887.764029496313;1889.6787546117193;822.411975437565;883.3790738415616;931.7763736703278;961.33667769239;1085.696005118568;20918.686219706717;21449.142034318764;22035;22438.439039024153;22492.87535623245;916.5188276154938;938.2962777916563;942.137943781446;968.1915600473543;978.5077756421514;9890.505474726235;11599.130065245141;11845.111616628787;11861.525538978516;11887.76224475523;582599;592909;610547;613562;623651;103018;116934;122259.99999999822;126355.36822316014;126950;808.890799742039;979.6522234606755;990.7374545745383;992.2062350119875;992.8014397120461;15410.845891540941;17731.556711082325;18815.623687526448;19046.90476547664;19264.8073519263;52528.73735631297;53266;55198.724006379714;55444.44555554393;56408;76284;77540.83688744786;77768;78372;78442.82335764854;8051.677932882619;8119.959400202961;8143.75568732939;8249.546274954695;8443.66225350979;10644.574217031231;13086.14940028895;13103.410346534238;13508.594742157751;13611.387487562886;10152.187824973837;10603.787924241626;10654.14766818668;11291;11773.411329433322;10310.278280520291;11134.164937629548;11309.208355415041;11442.942785286354;11775.352355620524;11495;11650.592229271935;11789.70525736829;11890.940545297564;11971.281723096643;6702.899456508254;8063.435559511013;8077.87883181741;8125.8374832504205;8337.499750015018;11147.442627868411;11191;11421.828672570084;11465.484053217458;11655.242409243372 04:14:50 INFO - PROCESS | 1942 | |3;mootools.html;1434.8134742483476;1477.416420513897;1616.3211451190498;1661.659359831234;1728.6542691461711;7526.6236688165645;7774.144844067162;8027.678892844287;8473.788155296113;8522.446041007337;7995.2804247617705;8278.461899976497;8291.005079390481;8432.747017589481;8458.3656225783;2308.099841061983;2340.414896275931;2346.6245400735884;2353.49399879026;2354.117206047732;1973.6052789442097;2334.731505876829;2359.245041586693;2379.8453100548495;2387.4628208653003;2322.059565875823;2338.929832105031;2348.941276468085;2350.118705485443;2357.222116701493;2282.8402011859175;2309.8960546775434;2339.040993192791;2344.0623750499767;2352.929412117639;2355.729091154522;2358.2217868103485;2367.195153647751;2369.087901158049;2388.36708272308;6895.896561551581;8312.501249925024;8410.32717382608;8410.537420441873;8441.746747597583;5081.695098294114;5231.869203269911;5235.397929238148;5235.869103272411;5250.264962905197;3270.640229574745;3381.509681096252;3510.2628448025944;3567.6075631680496;3572.6070132285427;1003.7741508160606;1026.712520494263;1063.5426766490407;1078.8543546621263;1081.2701426537055;10818.242723009393;10853.077488413493;10939.617113400993;10965.451727413598;10966.232363734622;4526.683132180781;4602;4689.343491911135;4692.882677933011;4772.0694464579765;9338.57976391064;9494.857577136348;9528;9540.856887146698;9549.09283618055;5209.843704688866;5213.478652134756;5236.869078273074;5238.240455133984;5244.108501554745;4220.198162349144;4231.640310573635;4510.909781804411;4769.165396055676;4798.928016079762;3684.5630204220374;3829.965909204499;3845.3270677631303;3945.605439456089;3957.782321972319;1160.5009845766401;1271.7790920716031;1290.4902563487558;1295.3587973952954;1299.564645843634;746.4364404874285;750.6809605917463;793.3811626930888;895.6104094718688;912.6212621762027;5633.887322253532;5685.630434021776;5775.942240577624;5814.912776308359;5877.647341159544;762.4510352546158;857.5755001274284;863.9611217495228;891.0465801592363;920.6961702638114;3018.758499320093;3646.34365814156;3970.1662650843646;4015.3976903464713;4056.634902858757;8973.37186396959;9406.717798466058;10157.085862272432;10188.235882308856;10210.693679189637;9667.27495437845;10118.898811011943;10146.239032072477;10228.59085636581;10232.976702329708;7705.537667739954;7910.9604451977375;7922.801929951797;7943.960280198562;7945.205479452125;5759.884802304015;5785.681787501728;5807.245058142415;5848.152017957287;6022.819315420369;9193.816123677623;9312.953435232781;9416.62333506652;9450.43297402158;9482.573284202366;8857.33569982241;9654;9676.080772326617;9677.854832177663;9678.032196780267;6589.374009469093;6822.590644561342;7080.610566418896;7216.134063912364;7351.522151060164;7875.409344299315;7969.561674107979;8073.7577872663915;8090.312323452573;8097.433179677366;7068.469864760266;7204.53170543913;7212.350888420067;7233.276672332725;7363.852722945619;6947.722091116501;7027.789166325018;7089.893651595127;7103.573785572882;7192.496525243392;6627.569208001464;6656.36764507371;6664.366885145903;6753.628550429774;6778.288279730557;4052;4123.36087906375;4839.516048395132;4928.334674818853;4969.105560999054;4842.685225460334;4858.076965376567;4862.708237505761;4992.176290912104;5099.490050994871 04:14:50 INFO - PROCESS | 1942 | |4;prototype.html;306.0268346657629;306.27260256889883;308.8656434451015;313.34979916672904;316.01718654983;4096.85661001865;4934.1365261079345;5115.744212789359;5178.275041494189;5201.947980520198;4873.123847318781;5015.673981191229;5044.016416798726;5060.4686507916595;5085.593152547796;3946.842126314944;3954.5254569451704;3956.643902048815;3991.2416640838146;3993.221321842242;3165.7942233754848;3360.4791257355027;3874.8062596870186;3980.24375368679;3985.641292283694;3347.464405695089;3518.014955812364;3806.9721175282657;3829.8468061277517;3853.287141878747;3840.289546433905;3847.480590120326;3851.480050193216;3857.4599556061894;3887.5334959804723;3375.8982835854554;3380.6957373836226;3392.5080863274934;3410.488426735985;3424.434968230239;4482.551744825524;4806.807727690888;4818.975905120487;4904.651220914241;4944.90110197794;3836.462895194675;4349.543297953701;4400.031992961543;4406.3916670415065;4407.559244075599;5037.219231019198;5042.773075211624;5338.839834804962;5376.677399356051;5381.838544843661;3479.9478007829903;3627.891163265106;3723.050622091376;3727.4408838674144;3739.513863197767;4887.7311747853855;5599.80400685974;5617.410171931929;5662.263905692274;5689.630174038674;3767.679747221462;3773.3585290500682;3783.3000894834545;3791.4123310886857;3802.4866643003393;3258.7881787683723;3824.7896365700153;3861.092643228839;3862.208247309295;3862.980685096613;3838.8272527736317;3997.2205419942834;4395.384646149541;4461.590740999889;4475.70907890986;4445.2887537993765;4814.903701926012;5249.947500525022;5450.236966824647;5478.616496845182;1026.7279171019686;1214.4534959268365;1217.3000524698334;1249.5193196062514;1264.37413480326;670.6412069542771;816.0330008939426;851.1063383447356;866.8526350520426;875.2210532625968;4132.834686612562;4193.97903010483;4888.560029597354;5008.624353173526;5085.64400491962;4425.296260939562;4642.419697537808;4800.783964721596;5418.756155972991;5447.237386765856;4151.543330233671;4946.8763280918265;4959.892045880486;5004.047611908991;5085;2386.809055275574;2769.6982418263383;2784.3317603775354;2809.3819359740824;2845.8719357628956;5131.666441681279;5325.786968521294;5597.702584592334;5635.464630860061;5759.424057594207;4411.897025743564;4860.736208585722;5026.472220416877;5063.544281014726;5092.745362731923;5654.134135735198;5672.986089938013;5676.858078547987;5681.744321505542;5687.857803554944;4735.928961065656;4976.8755781105765;4980.4272508660515;5334.67991920486;5373.247745315737;5285.207218917193;5296.510639999195;5314.601404894571;5318.521333080042;5320.920186197132;4840.370751802243;5415.702136382379;5672.773089076556;5674.687892165971;5711.457411545896;2843.4881721290367;3590.856365745341;3772.43413487979;3802.73380863348;3811.3901775715754;5599.748011339418;5603.80386686464;5606.3461278922005;5629.802956896653;5637.774489020393;5017.197248440232;5484.698341591251;5503.229547863222;5517.668939863621;5693.4021927696995;4354.716943398669;4665.720056796603;5285.312909321764;5457.61796674229;5473.972630136824;5069.619778516552;5183;5236.816711415082;5372.247885296139;5385.919211211756;4999.650094474398;5225.634205605571;5434.402215756185;5465.918011229915;5467.015937131355;4254.787260637017;4265.722728022669;4599.471060827914;5331.440198779227;5426.593005524681 04:14:50 INFO - PROCESS | 1942 | |5;yui.html;669.420950877491;694.8749225139479;756.2059837170971;762.8359902620936;778.4122987144705;14042.929785351072;14873.256337183138;14936.103833769981;14964.7007059859;15023;13554.593362199124;13555.728885422286;13560.98292628052;13580.456781728719;13619.863801361984;658.3613894522317;684.0594182998377;817.9550124743137;826.2274773087153;848.567230712335;796.4584082823677;821.7493664432345;841.6170642357713;843.1652663862762;845.0830848529353;865.8744482050131;881.3566096749377;885.0972008551274;885.1679421343916;885.9734207973739;860.5095095795399;865.2213008292587;873.3668090634303;876.4741155306829;877.7717793373705;615.9168512250834;616.6731632234922;617.253123720299;640.8686219325027;663.7046514301138;12925.482980680761;13045.08684392093;13052.15161014531;13077.411516481781;14433.92783036078;12046.939765301206;12162.939185304105;12233.20484168535;13410.597682069554;14024.92987535066;12119.818202726967;12274.263544187377;12280.877191228152;12358.382080895919;12761.553345632858;4369.737815731066;4378.190034843564;4397.494288156871;4456.420665313489;4552.704074235197;88046;93367.59948600776;93476.06523934742;93620.59569106401;94059.0594094064;10097.343672661253;10111.848322275171;10159.898401016044;10304.484775761182;10455.215858810467;19743.703844442345;20033.699494507593;20038.398848034583;20097.39707808768;20140.697889531668;7638.579878106758;7698.384129269645;7778;7931.484453510503;8014;4344.066025804467;4348.369486424451;4391.385206071152;4463.330500424897;4489.922418619508;3440.8451619677176;3500.7024402926036;3529.170644898447;3560.2345495718546;3563.322968635951;536.2920944353416;615.8114838362004;622.6762083716442;667.4026746062248;671.9160104986877;574.5030548575452;617.8208319587269;635.7806556737918;642.9035644653246;648.7015972652632;8794;8928.866067009;9023.954880225558;9121.270298376114;9230.218830123342;562.2657226155778;604.1089393145076;643.1992169748693;664.7341063574609;666.8432918264205;4454.888627784331;4580.702254353457;4583.876950147235;4593.470389322757;4692.225782745809;111057;114133;115542;116570;116794.24808627878;102354.46468302982;103428.96571034344;105262;106378;106591;90262;91101.63347549792;92673.5366323164;94363.52818235866;94650.52674736582;8854.867176992222;8962.372633915564;8969;8991.595378207865;9216.723498295061;46692.7665361671;48373.27440088331;56465.15302270387;59110.704446477495;60436;67674.66162669155;81910.59044704976;84231;85149;85521.57239213763;11106;11237.831432528683;11353.943230284127;11489;11653.359065251492;12387.504499820267;12445.315507647167;12470.189437686522;12545.184563003375;12653.87346126527;11674.24117432398;11706.297622142698;11817.468213930222;11984.340861252365;12044.75910481768;11391.886081139082;11822.645320640395;11853.051755859684;12267.263964162179;12271.07966902468;11360.1479889007;11449.885501145216;11465.31208127515;11889.048876090064;12034.398280085785;9719;9879.703608891745;9946.602135914773;10167.288289819642;10430.37417754937;9919.950400247953;10284.537195826355;10285.382877027103;10323.741906452398;10389.168866490512 04:14:50 INFO - PROCESS | 1942 | __end_tp_report 04:14:50 INFO - PROCESS | 1942 | __start_cc_report 04:14:50 INFO - PROCESS | 1942 | _x_x_mozilla_cycle_collect,375 04:14:50 INFO - PROCESS | 1942 | __end_cc_report 04:14:50 INFO - PROCESS | 1942 | __startTimestamp1481717690201__endTimestamp 04:14:50 INFO - PROCESS | 1942 | 04:14:50 INFO - PROCESS | 1942 | ------- Summary: start ------- 04:14:50 INFO - PROCESS | 1942 | Number of tests: 6 04:14:50 INFO - PROCESS | 1942 | 04:14:50 INFO - PROCESS | 1942 | [#0] dojo.html Cycles:165 Average:5653.16 Median:5869.65 stddev:653.25 (11.1%) stddev-sans-first:629.85 04:14:50 INFO - PROCESS | 1942 | Values: 3353.6 3357.4 3360.4 3382.9 3395.2 4505.8 5568.6 5600.4 5603.5 5613.3 5609.9 5633.7 5665.6 5670.1 5675.1 5173.5 5211.3 5218.2 5250.9 5280.3 5245.5 5256.3 5273.1 5275.1 5304.9 5132.1 5136.6 5256.4 5276.4 5312.5 5127.5 5175.6 5192.7 5212.9 5227.2 5075.3 5187.6 5218.7 5275.3 5296.4 5652.9 5726.7 5745.4 5758.1 5781.0 5996.9 6076.5 6109.8 6120.7 6134.0 5108.9 6105.0 6119.1 6125.9 6134.2 5696.9 5704.8 5716.6 5725.3 5728.9 6225.8 6229.3 6262.3 6278.4 6304.0 4896.7 5881.7 6016.7 6031.5 6100.9 6168.7 6179.4 6205.1 6215.2 6226.5 6122.4 6147.5 6170.0 6172.0 6176.3 5228.8 5934.6 5996.2 6126.4 6208.5 4529.2 4681.6 4694.5 4703.3 4742.3 4192.2 4195.5 4203.5 4225.3 4254.1 5190.6 5575.9 5625.1 5643.2 5651.1 6095.7 6117.5 6119.1 6130.6 6154.4 5760.6 5851.9 5907.7 5977.9 5986.3 5113.7 5140.7 5287.7 5304.6 5333.1 6261.8 6353.4 6384.3 6390.6 6392.6 6075.9 6129.1 6138.9 6215.3 6252.5 6232.7 6241.3 6292.3 6301.9 6397.4 6093.1 6098.2 6121.1 6134.9 6150.2 4893.7 6206.3 6213.9 6215.9 6239.8 5847.1 5884.9 5914.6 5988.6 6022.3 6012.3 6016.3 6084.3 6121.2 6139.8 5593.2 5839.5 5890.4 5950.1 5982.0 5861.1 5936.4 6056.1 6073.6 6090.4 5811.7 5847.5 5869.6 5877.4 5883.3 04:14:50 INFO - PROCESS | 1942 | 04:14:50 INFO - PROCESS | 1942 | [#1] ext.html Cycles:180 Average:69108.62 Median:42310.47 stddev:148565.54 (351.1%) stddev-sans-first:148892.95 04:14:50 INFO - PROCESS | 1942 | Values: 481.9 492.3 515.0 522.7 528.9 32689.8 39581.8 40989.4 41239.4 41280.6 42291.9 42596.6 42799.6 42859.6 43533.8 8100.3 9275.5 9425.4 9439.9 9457.8 9282.6 9352.4 9371.7 9444.5 9474.8 8224.4 9375.7 9442.0 9467.9 9468.4 9360.0 9428.4 9440.3 9440.5 9463.2 9302.0 9347.5 9380.6 9387.5 9394.8 39618.0 40527.6 40623.4 40764.0 40843.2 3341.3 3350.4 3549.2 3831.3 3960.1 29979.6 30457.1 30640.4 30799.0 31163.5 3104.8 3212.6 3242.8 3275.5 3355.0 497761.0 502543.0 502672.5 505229.0 506401.0 51657.0 51718.0 51807.7 51882.0 51978.7 63811.0 63817.7 63980.0 64483.0 64757.7 16036.0 16283.6 16477.7 16496.8 16527.3 12619.0 12645.7 12681.0 12681.5 12717.8 17419.8 17758.6 17794.0 17858.5 17861.8 5535.9 5548.3 6494.7 6605.7 6668.5 849.9 855.5 923.8 962.6 1092.7 30347.5 30372.4 30374.7 30705.7 30774.5 890.7 924.3 1039.4 1042.9 1047.6 15143.6 15447.7 15792.0 15815.7 15952.3 601045.0 642232.6 671031.0 671732.0 673612.0 58640.0 58686.1 58723.1 58779.0 58903.0 1370.2 1385.0 1390.5 1393.5 1396.0 29431.0 29627.3 30210.5 30237.2 30911.5 52409.5 52655.5 52694.9 52724.0 52797.5 499680.0 499802.0 499944.5 501490.0 502238.0 21316.7 25429.1 25546.2 25802.1 27051.3 46663.0 46776.8 48058.0 48106.8 48332.0 39235.6 39535.0 40140.8 40196.0 40779.4 41963.4 42084.6 42610.8 43345.0 44919.3 34588.0 35178.5 38584.8 39272.6 40484.2 37791.1 41242.6 41360.4 42022.8 42329.0 30414.8 31512.7 31650.0 31819.5 32078.6 04:14:50 INFO - PROCESS | 1942 | 04:14:50 INFO - PROCESS | 1942 | [#2] jquery.html Cycles:180 Average:34817.46 Median:25155.25 stddev:100493.31 (399.5%) stddev-sans-first:100741.93 04:14:50 INFO - PROCESS | 1942 | Values: 372.7 378.8 388.1 388.4 405.2 17787.6 18475.0 20617.7 20774.8 20873.5 17490.6 20298.4 20302.4 20304.6 20814.4 4652.7 4807.4 5625.2 5800.2 5832.4 4901.9 5683.8 5805.3 5813.2 5830.0 5442.4 5700.9 5755.3 5757.1 5788.5 5768.9 5818.9 5824.9 5831.2 5833.0 5002.1 5007.5 5037.1 5049.1 5124.6 19576.5 20077.8 24859.0 25110.7 25199.7 2266.3 2296.1 2494.2 2592.3 2779.7 13426.7 14411.0 15569.3 15894.5 15993.0 1860.1 1872.1 1877.6 1903.2 1947.2 93635.0 102975.0 114616.0 118232.0 119110.0 10731.4 10940.5 11055.2 11075.5 11193.3 11223.5 13420.2 13686.8 13753.7 13764.9 8335.7 8612.2 8658.4 8752.3 8783.5 7266.6 8649.5 8702.6 8713.8 8888.2 5253.8 6771.3 6783.7 6818.4 6860.6 1847.3 1882.9 1884.1 1887.8 1889.7 822.4 883.4 931.8 961.3 1085.7 20918.7 21449.1 22035.0 22438.4 22492.9 916.5 938.3 942.1 968.2 978.5 9890.5 11599.1 11845.1 11861.5 11887.8 582599.0 592909.0 610547.0 613562.0 623651.0 103018.0 116934.0 122260.0 126355.4 126950.0 808.9 979.7 990.7 992.2 992.8 15410.8 17731.6 18815.6 19046.9 19264.8 52528.7 53266.0 55198.7 55444.4 56408.0 76284.0 77540.8 77768.0 78372.0 78442.8 8051.7 8120.0 8143.8 8249.5 8443.7 10644.6 13086.1 13103.4 13508.6 13611.4 10152.2 10603.8 10654.1 11291.0 11773.4 10310.3 11134.2 11309.2 11442.9 11775.4 11495.0 11650.6 11789.7 11890.9 11971.3 6702.9 8063.4 8077.9 8125.8 8337.5 11147.4 11191.0 11421.8 11465.5 11655.2 04:14:50 INFO - PROCESS | 1942 | 04:14:50 INFO - PROCESS | 1942 | [#3] mootools.html Cycles:180 Average:5458.59 Median:5233.63 stddev:2986.09 (57.1%) stddev-sans-first:2979.15 04:14:50 INFO - PROCESS | 1942 | Values: 1434.8 1477.4 1616.3 1661.7 1728.7 7526.6 7774.1 8027.7 8473.8 8522.4 7995.3 8278.5 8291.0 8432.7 8458.4 2308.1 2340.4 2346.6 2353.5 2354.1 1973.6 2334.7 2359.2 2379.8 2387.5 2322.1 2338.9 2348.9 2350.1 2357.2 2282.8 2309.9 2339.0 2344.1 2352.9 2355.7 2358.2 2367.2 2369.1 2388.4 6895.9 8312.5 8410.3 8410.5 8441.7 5081.7 5231.9 5235.4 5235.9 5250.3 3270.6 3381.5 3510.3 3567.6 3572.6 1003.8 1026.7 1063.5 1078.9 1081.3 10818.2 10853.1 10939.6 10965.5 10966.2 4526.7 4602.0 4689.3 4692.9 4772.1 9338.6 9494.9 9528.0 9540.9 9549.1 5209.8 5213.5 5236.9 5238.2 5244.1 4220.2 4231.6 4510.9 4769.2 4798.9 3684.6 3830.0 3845.3 3945.6 3957.8 1160.5 1271.8 1290.5 1295.4 1299.6 746.4 750.7 793.4 895.6 912.6 5633.9 5685.6 5775.9 5814.9 5877.6 762.5 857.6 864.0 891.0 920.7 3018.8 3646.3 3970.2 4015.4 4056.6 8973.4 9406.7 10157.1 10188.2 10210.7 9667.3 10118.9 10146.2 10228.6 10233.0 7705.5 7911.0 7922.8 7944.0 7945.2 5759.9 5785.7 5807.2 5848.2 6022.8 9193.8 9313.0 9416.6 9450.4 9482.6 8857.3 9654.0 9676.1 9677.9 9678.0 6589.4 6822.6 7080.6 7216.1 7351.5 7875.4 7969.6 8073.8 8090.3 8097.4 7068.5 7204.5 7212.4 7233.3 7363.9 6947.7 7027.8 7089.9 7103.6 7192.5 6627.6 6656.4 6664.4 6753.6 6778.3 4052.0 4123.4 4839.5 4928.3 4969.1 4842.7 4858.1 4862.7 4992.2 5099.5 04:14:50 INFO - PROCESS | 1942 | 04:14:50 INFO - PROCESS | 1942 | [#4] prototype.html Cycles:180 Average:4310.65 Median:4866.93 stddev:1328.87 (27.3%) stddev-sans-first:1298.16 04:14:50 INFO - PROCESS | 1942 | Values: 306.0 306.3 308.9 313.3 316.0 4096.9 4934.1 5115.7 5178.3 5201.9 4873.1 5015.7 5044.0 5060.5 5085.6 3946.8 3954.5 3956.6 3991.2 3993.2 3165.8 3360.5 3874.8 3980.2 3985.6 3347.5 3518.0 3807.0 3829.8 3853.3 3840.3 3847.5 3851.5 3857.5 3887.5 3375.9 3380.7 3392.5 3410.5 3424.4 4482.6 4806.8 4819.0 4904.7 4944.9 3836.5 4349.5 4400.0 4406.4 4407.6 5037.2 5042.8 5338.8 5376.7 5381.8 3479.9 3627.9 3723.1 3727.4 3739.5 4887.7 5599.8 5617.4 5662.3 5689.6 3767.7 3773.4 3783.3 3791.4 3802.5 3258.8 3824.8 3861.1 3862.2 3863.0 3838.8 3997.2 4395.4 4461.6 4475.7 4445.3 4814.9 5249.9 5450.2 5478.6 1026.7 1214.5 1217.3 1249.5 1264.4 670.6 816.0 851.1 866.9 875.2 4132.8 4194.0 4888.6 5008.6 5085.6 4425.3 4642.4 4800.8 5418.8 5447.2 4151.5 4946.9 4959.9 5004.0 5085.0 2386.8 2769.7 2784.3 2809.4 2845.9 5131.7 5325.8 5597.7 5635.5 5759.4 4411.9 4860.7 5026.5 5063.5 5092.7 5654.1 5673.0 5676.9 5681.7 5687.9 4735.9 4976.9 4980.4 5334.7 5373.2 5285.2 5296.5 5314.6 5318.5 5320.9 4840.4 5415.7 5672.8 5674.7 5711.5 2843.5 3590.9 3772.4 3802.7 3811.4 5599.7 5603.8 5606.3 5629.8 5637.8 5017.2 5484.7 5503.2 5517.7 5693.4 4354.7 4665.7 5285.3 5457.6 5474.0 5069.6 5183.0 5236.8 5372.2 5385.9 4999.7 5225.6 5434.4 5465.9 5467.0 4254.8 4265.7 4599.5 5331.4 5426.6 04:14:50 INFO - PROCESS | 1942 | 04:14:50 INFO - PROCESS | 1942 | [#5] yui.html Cycles:180 Average:21271.63 Median:4521.31 stddev:32235.46 (713.0%) stddev-sans-first:32288.77 04:14:50 INFO - PROCESS | 1942 | Values: 669.4 694.9 756.2 762.8 778.4 14042.9 14873.3 14936.1 14964.7 15023.0 13554.6 13555.7 13561.0 13580.5 13619.9 658.4 684.1 818.0 826.2 848.6 796.5 821.7 841.6 843.2 845.1 865.9 881.4 885.1 885.2 886.0 860.5 865.2 873.4 876.5 877.8 615.9 616.7 617.3 640.9 663.7 12925.5 13045.1 13052.2 13077.4 14433.9 12046.9 12162.9 12233.2 13410.6 14024.9 12119.8 12274.3 12280.9 12358.4 12761.6 4369.7 4378.2 4397.5 4456.4 4552.7 88046.0 93367.6 93476.1 93620.6 94059.1 10097.3 10111.8 10159.9 10304.5 10455.2 19743.7 20033.7 20038.4 20097.4 20140.7 7638.6 7698.4 7778.0 7931.5 8014.0 4344.1 4348.4 4391.4 4463.3 4489.9 3440.8 3500.7 3529.2 3560.2 3563.3 536.3 615.8 622.7 667.4 671.9 574.5 617.8 635.8 642.9 648.7 8794.0 8928.9 9024.0 9121.3 9230.2 562.3 604.1 643.2 664.7 666.8 4454.9 4580.7 4583.9 4593.5 4692.2 111057.0 114133.0 115542.0 116570.0 116794.2 102354.5 103429.0 105262.0 106378.0 106591.0 90262.0 91101.6 92673.5 94363.5 94650.5 8854.9 8962.4 8969.0 8991.6 9216.7 46692.8 48373.3 56465.2 59110.7 60436.0 67674.7 81910.6 84231.0 85149.0 85521.6 11106.0 11237.8 11353.9 11489.0 11653.4 12387.5 12445.3 12470.2 12545.2 12653.9 11674.2 11706.3 11817.5 11984.3 12044.8 11391.9 11822.6 11853.1 12267.3 12271.1 11360.1 11449.9 11465.3 11889.0 12034.4 9719.0 9879.7 9946.6 10167.3 10430.4 9920.0 10284.5 10285.4 10323.7 10389.2 04:14:50 INFO - PROCESS | 1942 | -------- Summary: end -------- 04:14:50 INFO - PROCESS | 1942 | 04:14:50 INFO - TEST-INFO | 1942: exit 0 04:14:50 INFO - TEST-OK | dromaeo_css | took 1117147ms 04:14:50 INFO - TEST-START | kraken 04:14:50 INFO - operating with platform_type : mac_ 04:14:50 INFO - Initialising browser for kraken test... 04:14:50 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpr_aypI/profile http://localhost:54566/getInfo.html) 04:14:53 INFO - PROCESS | 1948 | 2016-12-14 04:14:53.819 plugin-container[1950:21277] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 04:14:53 INFO - PROCESS | 1948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:14:53 INFO - PROCESS | 1948 | 2016-12-14 04:14:53.819 plugin-container[1950:21277] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 04:14:53 INFO - PROCESS | 1948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:14:53 INFO - PROCESS | 1948 | 2016-12-14 04:14:53.819 plugin-container[1950:21277] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:14:53 INFO - PROCESS | 1948 | __metrics Screen width/height:1600/1200 04:14:53 INFO - PROCESS | 1948 | colorDepth:24 04:14:53 INFO - PROCESS | 1948 | Browser inner width/height: 1024/689 04:14:53 INFO - PROCESS | 1948 | __metrics 04:14:54 ERROR - PROCESS | 1948 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 04:14:54 INFO - TEST-INFO | 1948: exit 0 04:14:54 INFO - Browser initialized. 04:14:54 INFO - Running cycle 1/1 for kraken test... 04:14:54 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpr_aypI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:14:57 INFO - PROCESS | 1951 | 2016-12-14 04:14:57.614 plugin-container[1952:21399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8637, name = 'com.apple.tsm.portname' 04:14:57 INFO - PROCESS | 1951 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:14:57 INFO - PROCESS | 1951 | 2016-12-14 04:14:57.615 plugin-container[1952:21399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d23, name = 'com.apple.CFPasteboardClient' 04:14:57 INFO - PROCESS | 1951 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:14:57 INFO - PROCESS | 1951 | 2016-12-14 04:14:57.615 plugin-container[1952:21399] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:15:07 INFO - PROCESS | 1951 | RSS: Main: 229634048 04:15:07 INFO - PROCESS | 1951 | 04:15:10 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54566/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54566/tests/kraken/driver.html?testName=audio-beat-detection) 04:15:11 INFO - PROCESS | 1951 | RSS: Main: 241520640 04:15:11 INFO - PROCESS | 1951 | 04:15:13 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54566/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54566/tests/kraken/driver.html?testName=audio-dft) 04:15:14 INFO - PROCESS | 1951 | RSS: Main: 249184256 04:15:14 INFO - PROCESS | 1951 | 04:15:18 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54566/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54566/tests/kraken/driver.html?testName=audio-fft) 04:15:18 INFO - PROCESS | 1951 | RSS: Main: 252891136 04:15:18 INFO - PROCESS | 1951 | 04:15:20 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54566/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54566/tests/kraken/driver.html?testName=audio-oscillator) 04:15:21 INFO - PROCESS | 1951 | RSS: Main: 254103552 04:15:21 INFO - PROCESS | 1951 | 04:15:23 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54566/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54566/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:15:23 INFO - PROCESS | 1951 | RSS: Main: 254414848 04:15:23 INFO - PROCESS | 1951 | 04:15:26 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54566/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54566/tests/kraken/driver.html?testName=imaging-darkroom) 04:15:27 INFO - PROCESS | 1951 | RSS: Main: 258445312 04:15:27 INFO - PROCESS | 1951 | 04:15:30 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54566/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54566/tests/kraken/driver.html?testName=imaging-desaturate) 04:15:30 INFO - PROCESS | 1951 | RSS: Main: 257974272 04:15:30 INFO - PROCESS | 1951 | 04:15:33 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54566/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54566/tests/kraken/driver.html?testName=json-parse-financial) 04:15:34 INFO - PROCESS | 1951 | RSS: Main: 258428928 04:15:34 INFO - PROCESS | 1951 | 04:15:36 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54566/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54566/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:15:36 INFO - PROCESS | 1951 | RSS: Main: 257019904 04:15:36 INFO - PROCESS | 1951 | 04:15:38 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54566/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54566/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:15:39 INFO - PROCESS | 1951 | RSS: Main: 257019904 04:15:39 INFO - PROCESS | 1951 | 04:15:41 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54566/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54566/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:15:42 INFO - PROCESS | 1951 | RSS: Main: 257073152 04:15:42 INFO - PROCESS | 1951 | 04:15:45 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54566/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54566/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:15:46 INFO - PROCESS | 1951 | RSS: Main: 257617920 04:15:46 INFO - PROCESS | 1951 | 04:15:49 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54566/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54566/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:15:49 INFO - PROCESS | 1951 | RSS: Main: 257458176 04:15:49 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:54566/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54566/tests/kraken/driver.html?testName=ai-astar) 04:15:51 INFO - PROCESS | 1951 | RSS: Main: 259301376 04:15:51 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | __start_tp_report 04:15:51 INFO - PROCESS | 1951 | _x_x_mozilla_page_load 04:15:51 INFO - PROCESS | 1951 | _x_x_mozilla_page_load_details 04:15:51 INFO - PROCESS | 1951 | |i|pagename|runs| 04:15:51 INFO - PROCESS | 1951 | |0;ai-astar;87;176;176;133;119;119;115;119;118;117 04:15:51 INFO - PROCESS | 1951 | |1;audio-beat-detection;179;116;111;144;96;127;145;125;140;111 04:15:51 INFO - PROCESS | 1951 | |2;audio-dft;200;198;199;200;198;265;253;203;197;197 04:15:51 INFO - PROCESS | 1951 | |3;audio-fft;90;83;81;82;61;58;58;61;86;79 04:15:51 INFO - PROCESS | 1951 | |4;audio-oscillator;89;72;91;71;69;69;86;69;70;87 04:15:51 INFO - PROCESS | 1951 | |5;imaging-gaussian-blur;88;89;88;73;132;85;88;86;89;85 04:15:51 INFO - PROCESS | 1951 | |6;imaging-darkroom;96;128;133;126;92;144;157;108;96;108 04:15:51 INFO - PROCESS | 1951 | |7;imaging-desaturate;92;94;125;78;142;140;95;92;90;90 04:15:51 INFO - PROCESS | 1951 | |8;json-parse-financial;56;55;59;68;87;92;96;92;93;60 04:15:51 INFO - PROCESS | 1951 | |9;json-stringify-tinderbox;70;72;73;81;75;73;71;72;74;71 04:15:51 INFO - PROCESS | 1951 | |10;stanford-crypto-aes;86;85;92;85;87;88;77;95;103;105 04:15:51 INFO - PROCESS | 1951 | |11;stanford-crypto-ccm;155;149;152;146;151;154;160;160;216;185 04:15:51 INFO - PROCESS | 1951 | |12;stanford-crypto-pbkdf2;169;173;167;171;168;237;226;188;211;155 04:15:51 INFO - PROCESS | 1951 | |13;stanford-crypto-sha256-iterative;71;67;69;69;65;62;79;88;61;77 04:15:51 INFO - PROCESS | 1951 | __end_tp_report 04:15:51 INFO - PROCESS | 1951 | __start_cc_report 04:15:51 INFO - PROCESS | 1951 | _x_x_mozilla_cycle_collect,984 04:15:51 INFO - PROCESS | 1951 | __end_cc_report 04:15:51 INFO - PROCESS | 1951 | __startTimestamp1481717751928__endTimestamp 04:15:51 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | ------- Summary: start ------- 04:15:51 INFO - PROCESS | 1951 | Number of tests: 14 04:15:51 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | [#0] ai-astar Cycles:10 Average:127.90 Median:126.00 stddev:27.78 (22.0%) stddev-sans-first:25.22 04:15:51 INFO - PROCESS | 1951 | Values: 87.0 176.0 176.0 133.0 119.0 119.0 115.0 119.0 118.0 117.0 04:15:51 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:142.00 stddev:23.63 (16.6%) stddev-sans-first:16.93 04:15:51 INFO - PROCESS | 1951 | Values: 179.0 116.0 111.0 144.0 96.0 127.0 145.0 125.0 140.0 111.0 04:15:51 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | [#2] audio-dft Cycles:10 Average:211.00 Median:200.00 stddev:25.52 (12.8%) stddev-sans-first:26.75 04:15:51 INFO - PROCESS | 1951 | Values: 200.0 198.0 199.0 200.0 198.0 265.0 253.0 203.0 197.0 197.0 04:15:51 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | [#3] audio-fft Cycles:10 Average:73.90 Median:81.50 stddev:12.78 (15.7%) stddev-sans-first:12.15 04:15:51 INFO - PROCESS | 1951 | Values: 90.0 83.0 81.0 82.0 61.0 58.0 58.0 61.0 86.0 79.0 04:15:51 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:79.00 stddev:9.56 (12.1%) stddev-sans-first:9.15 04:15:51 INFO - PROCESS | 1951 | Values: 89.0 72.0 91.0 71.0 69.0 69.0 86.0 69.0 70.0 87.0 04:15:51 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:88.00 stddev:15.39 (17.5%) stddev-sans-first:16.30 04:15:51 INFO - PROCESS | 1951 | Values: 88.0 89.0 88.0 73.0 132.0 85.0 88.0 86.0 89.0 85.0 04:15:51 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | [#6] imaging-darkroom Cycles:10 Average:118.80 Median:150.50 stddev:22.17 (14.7%) stddev-sans-first:21.93 04:15:51 INFO - PROCESS | 1951 | Values: 96.0 128.0 133.0 126.0 92.0 144.0 157.0 108.0 96.0 108.0 04:15:51 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | [#7] imaging-desaturate Cycles:10 Average:103.80 Median:91.00 stddev:22.89 (25.2%) stddev-sans-first:23.88 04:15:51 INFO - PROCESS | 1951 | Values: 92.0 94.0 125.0 78.0 142.0 140.0 95.0 92.0 90.0 90.0 04:15:51 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | [#8] json-parse-financial Cycles:10 Average:75.80 Median:89.50 stddev:17.55 (19.6%) stddev-sans-first:17.09 04:15:51 INFO - PROCESS | 1951 | Values: 56.0 55.0 59.0 68.0 87.0 92.0 96.0 92.0 93.0 60.0 04:15:51 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | [#9] json-stringify-tinderbox Cycles:10 Average:73.20 Median:73.00 stddev:3.12 (4.3%) stddev-sans-first:3.09 04:15:51 INFO - PROCESS | 1951 | Values: 70.0 72.0 73.0 81.0 75.0 73.0 71.0 72.0 74.0 71.0 04:15:51 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:86.50 stddev:8.63 (10.0%) stddev-sans-first:9.01 04:15:51 INFO - PROCESS | 1951 | Values: 86.0 85.0 92.0 85.0 87.0 88.0 77.0 95.0 103.0 105.0 04:15:51 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | [#11] stanford-crypto-ccm Cycles:10 Average:162.80 Median:157.50 stddev:21.62 (13.7%) stddev-sans-first:22.74 04:15:51 INFO - PROCESS | 1951 | Values: 155.0 149.0 152.0 146.0 151.0 154.0 160.0 160.0 216.0 185.0 04:15:51 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.50 Median:180.50 stddev:28.20 (15.6%) stddev-sans-first:29.19 04:15:51 INFO - PROCESS | 1951 | Values: 169.0 173.0 167.0 171.0 168.0 237.0 226.0 188.0 211.0 155.0 04:15:51 INFO - PROCESS | 1951 | 04:15:51 INFO - PROCESS | 1951 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:70.00 stddev:8.36 (11.9%) stddev-sans-first:8.87 04:15:51 INFO - PROCESS | 1951 | Values: 71.0 67.0 69.0 69.0 65.0 62.0 79.0 88.0 61.0 77.0 04:15:51 INFO - PROCESS | 1951 | -------- Summary: end -------- 04:15:51 INFO - PROCESS | 1951 | 04:15:52 INFO - TEST-INFO | 1951: exit 0 04:15:52 INFO - TEST-OK | kraken | took 61979ms 04:15:52 INFO - SUITE-END | took 1179s 04:15:53 INFO - Completed test suite (00:19:39) 04:15:53 INFO - javascript benchmark 04:15:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7159.862434992717, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3353.564036675232, 3357.446021406468, 3360.4119279126153, 3382.915427114322, 3395.2360718838263, 4505.819767209307, 5568.638038527498, 5600.383957764642, 5603.523700485453, 5613.270274864267, 5609.859753506164, 5633.7183140843, 5665.603407761459, 5670.064439367509, 5675.148727690838, 5173.456787037363, 5211.296474975868, 5218.243354713583, 5250.894982100355, 5280.287161233242, 5245.527902488775, 5256.26412302278, 5273.077211487994, 5275.103232450473, 5304.946950530503, 5132.076226279269, 5136.614753893455, 5256.369235691723, 5276.445973172819, 5312.521873031426, 5127.461616530248, 5175.637705360588, 5192.662476938987, 5212.869678258036, 5227.189785583221, 5075.3402057732255, 5187.636865419422, 5218.712970786605, 5275.287836142148, 5296.443873393316, 5652.886942261173, 5726.713664316809, 5745.396733343023, 5758.107493338539, 5781.046127388976, 5996.940030599681, 6076.544259180535, 6109.847253818646, 6120.693965301762, 6133.969330153321, 5108.948910510921, 6105.0, 6119.143319935212, 6125.876495711611, 6134.161069136565, 5696.914546281768, 5704.771809127671, 5716.5998380113, 5725.255716756795, 5728.914066288968, 6225.750969961241, 6229.252489701174, 6262.3111457739615, 6278.4035516625845, 6303.968480157661, 4896.681715688469, 5881.735321910524, 6016.69916504173, 6031.517478601701, 6100.908486372708, 6168.722407491674, 6179.412955769195, 6205.131281620576, 6215.192025036807, 6226.533010024266, 6122.357152498924, 6147.508199344042, 6170.043643235305, 6172.043333283348, 6176.289726681399, 5228.816991405282, 5934.643921364645, 5996.190514280516, 6126.36500017443, 6208.534359923024, 4529.230030894756, 4681.625469962395, 4694.483606803249, 4703.270992996091, 4742.33607294979, 4192.245395828719, 4195.475565554306, 4203.495580530356, 4225.323948168279, 4254.085371645111, 5190.6107041972, 5575.916361254584, 5625.071863142535, 5643.181738647955, 5651.0675738503505, 6095.664738439428, 6117.4620395853735, 6119.114362379921, 6130.581587307925, 6154.353792851686, 5760.596758226968, 5851.912221316684, 5907.734151963172, 5977.940220597825, 5986.341502434729, 5113.693178409308, 5140.665856719302, 5287.656302340336, 5304.602154838402, 5333.093374126331, 6261.843453913598, 6353.396427339396, 6384.32964538726, 6390.584612000205, 6392.552521323462, 6075.939240607538, 6129.1112788644505, 6138.877222455437, 6215.316315205233, 6252.531060170596, 6232.688365581612, 6241.282252541016, 6292.307846137013, 6301.936980630135, 6397.360263973565, 6093.086037094471, 6098.237720284965, 6121.081837724377, 6134.907976380268, 6150.231221097363, 4893.657443978887, 6206.348333424925, 6213.937860621517, 6215.875682486416, 6239.844003899939, 5847.093700476433, 5884.882302354014, 5914.58597898143, 5988.610740301867, 6022.307434645072, 6012.3386427493815, 6016.308124565731, 6084.269887613515, 6121.204243448323, 6139.815805525842, 5593.161025846156, 5839.532837373085, 5890.381509941566, 5950.137230101697, 5982.042873140276, 5861.062230043172, 5936.436038576328, 6056.091586262096, 6073.605215660968, 6090.390960904051, 5811.738471768866, 5847.532197424111, 5869.647821130746, 5877.441643043904, 5883.294004719461], "value": 5612.074886817835, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.8506263058452, 492.33534728117024, 514.9880484847275, 522.7281813457, 528.9444608316126, 32689.836550817214, 39581.80209098954, 40989.3851592226, 41239.38140927881, 41280.55518056872, 42291.94270143248, 42596.57403425973, 42799.57200427995, 42859.57140428595, 43533.782331088376, 8100.270975612194, 9275.48984805837, 9425.434473931586, 9439.914409842853, 9457.81084378312, 9282.628694852197, 9352.392094513869, 9371.718848434557, 9444.48055356955, 9474.81050378992, 8224.383171262149, 9375.718728438123, 9442.0, 9467.905320946771, 9468.431894086343, 9359.95320023399, 9428.434293942317, 9440.29197810167, 9440.527973601327, 9463.195628371597, 9301.953490232505, 9347.532623368805, 9380.577873995619, 9387.530623468867, 9394.765130871714, 39618.0, 40527.59472405297, 40623.39064914029, 40764.0, 40843.18313633741, 3341.331733653279, 3350.363430948112, 3549.2191717822298, 3831.3295173344554, 3960.1485680578526, 29979.550306745416, 30457.08628741119, 30640.387192256254, 30799.0, 31163.53254701181, 3104.8447577621027, 3212.550242965987, 3242.837858107085, 3275.54142420059, 3354.9832250838836, 497761.0, 502543.0, 502672.4866375681, 505229.0, 506401.0, 51656.96686066258, 51718.0, 51807.740961295705, 51881.96236075333, 51978.74010629923, 63811.0, 63817.68091159515, 63980.0, 64483.0, 64757.70484590376, 16036.0, 16283.59291017734, 16477.670446591, 16496.75254871178, 16527.33890644385, 12619.0, 12645.747085058247, 12681.0, 12681.49274029047, 12717.809232861515, 17419.82580174182, 17758.644827103384, 17794.0, 17858.46424607264, 17861.821381786016, 5535.861603459945, 5548.250986116904, 6494.740210391626, 6605.735770569124, 6668.466522678271, 849.8767678686556, 855.5422848776, 923.8290916180528, 962.6245764151986, 1092.6831218946575, 30347.544786828214, 30372.392552149275, 30374.69625303763, 30705.692943070284, 30774.53838192429, 890.7440508882445, 924.3437159616748, 1039.3919557059055, 1042.9426381549088, 1047.6490375724218, 15143.621409464851, 15447.691046179014, 15791.973521721051, 15815.68368632644, 15952.2821473034, 601045.0, 642232.5776742266, 671031.0, 671732.0, 673612.0, 58640.0, 58686.11970820441, 58723.119153212734, 58779.0, 58903.0, 1370.205280937054, 1384.9889580606014, 1390.5341710526884, 1393.540131756518, 1396.0, 29431.0, 29627.259318517208, 30210.54684179695, 30237.244068898453, 30911.536326954665, 52409.47590524046, 52655.47344526506, 52694.946101076996, 52724.0, 52797.472025279254, 499680.0, 499802.0, 499944.5002774963, 501490.0, 502238.0, 21316.680249795954, 25429.10998115057, 25546.23361299164, 25802.09692660748, 27051.323716907234, 46663.0, 46776.7661161692, 48058.0, 48106.75946620244, 48332.0, 39235.60764392434, 39535.0, 40140.79929600333, 40196.0, 40779.38830917479, 41963.37054944239, 42084.57915420806, 42610.78694606507, 43345.0, 44919.29009190601, 34588.0, 35178.47232291569, 38584.80707596444, 39272.60727392689, 40484.19031619292, 37791.05522361963, 41242.58757412388, 41360.37959430551, 42022.78988605038, 42329.0, 30414.84792576023, 31512.684873150974, 31650.0, 31819.522707158947, 32078.556464958667], "value": 19929.323784716882, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.7167352811863, 378.7840930669519, 388.05315031323585, 388.3514530733675, 405.17142443702625, 17787.64424711505, 18474.99999999998, 20617.690734638993, 20774.753514789125, 20873.478163045933, 17490.56273593161, 20298.391048268575, 20302.39092827214, 20304.593908121828, 20814.375568732925, 4652.697574657654, 4807.399075115611, 5625.1843482694985, 5800.158976948361, 5832.387599302055, 4901.901961960759, 5683.772649094052, 5805.303363596353, 5813.157092221625, 5829.9708501457435, 5442.428545002777, 5700.857478563048, 5755.309362876461, 5757.107648314518, 5788.5369170466265, 5768.884622307552, 5818.912716309238, 5824.883502329951, 5831.154482600042, 5833.0, 5002.099622068026, 5007.474215207387, 5037.143685573461, 5049.091163590552, 5124.615653825978, 19576.51058723529, 20077.79922200774, 24859.0, 25110.748892511023, 25199.748002519922, 2266.3427605994243, 2296.1045192374986, 2494.1519883239785, 2592.324763733394, 2779.722027797224, 13426.657334266592, 14411.0, 15569.299381527746, 15894.52316430509, 15993.0, 1860.1071485686812, 1872.0826794870552, 1877.6244751049708, 1903.2101677803832, 1947.20164732459, 93635.0, 102975.0, 114616.0, 118232.0, 119110.0, 10731.356118632752, 10940.45297735126, 11055.22613417053, 11075.50160242791, 11193.328400296008, 11223.494942727597, 13420.194788312732, 13686.794698079537, 13753.724925501434, 13764.93117534406, 8335.708250211335, 8612.224899759052, 8658.350623703247, 8752.299816014704, 8783.5169065702, 7266.63666816657, 8649.481031138152, 8702.608382622797, 8713.825723485621, 8888.24449921751, 5253.763580638804, 6771.28901465339, 6783.660816959231, 6818.352256535622, 6860.5540639858245, 1847.3072597775833, 1882.9246830126642, 1884.0500082414635, 1887.764029496313, 1889.6787546117193, 822.411975437565, 883.3790738415616, 931.7763736703278, 961.33667769239, 1085.696005118568, 20918.686219706717, 21449.142034318764, 22035.0, 22438.439039024153, 22492.87535623245, 916.5188276154938, 938.2962777916563, 942.137943781446, 968.1915600473543, 978.5077756421514, 9890.505474726235, 11599.130065245141, 11845.111616628787, 11861.525538978516, 11887.76224475523, 582599.0, 592909.0, 610547.0, 613562.0, 623651.0, 103018.0, 116934.0, 122259.99999999822, 126355.36822316014, 126950.0, 808.890799742039, 979.6522234606755, 990.7374545745383, 992.2062350119875, 992.8014397120461, 15410.845891540941, 17731.556711082325, 18815.623687526448, 19046.90476547664, 19264.8073519263, 52528.73735631297, 53266.0, 55198.724006379714, 55444.44555554393, 56408.0, 76284.0, 77540.83688744786, 77768.0, 78372.0, 78442.82335764854, 8051.677932882619, 8119.959400202961, 8143.75568732939, 8249.546274954695, 8443.66225350979, 10644.574217031231, 13086.14940028895, 13103.410346534238, 13508.594742157751, 13611.387487562886, 10152.187824973837, 10603.787924241626, 10654.14766818668, 11291.0, 11773.411329433322, 10310.278280520291, 11134.164937629548, 11309.208355415041, 11442.942785286354, 11775.352355620524, 11495.0, 11650.592229271935, 11789.70525736829, 11890.940545297564, 11971.281723096643, 6702.899456508254, 8063.435559511013, 8077.87883181741, 8125.8374832504205, 8337.499750015018, 11147.442627868411, 11191.0, 11421.828672570084, 11465.484053217458, 11655.242409243372], "value": 9646.847461581694, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1434.8134742483476, 1477.416420513897, 1616.3211451190498, 1661.659359831234, 1728.6542691461711, 7526.6236688165645, 7774.144844067162, 8027.678892844287, 8473.788155296113, 8522.446041007337, 7995.2804247617705, 8278.461899976497, 8291.005079390481, 8432.747017589481, 8458.3656225783, 2308.099841061983, 2340.414896275931, 2346.6245400735884, 2353.49399879026, 2354.117206047732, 1973.6052789442097, 2334.731505876829, 2359.245041586693, 2379.8453100548495, 2387.4628208653003, 2322.059565875823, 2338.929832105031, 2348.941276468085, 2350.118705485443, 2357.222116701493, 2282.8402011859175, 2309.8960546775434, 2339.040993192791, 2344.0623750499767, 2352.929412117639, 2355.729091154522, 2358.2217868103485, 2367.195153647751, 2369.087901158049, 2388.36708272308, 6895.896561551581, 8312.501249925024, 8410.32717382608, 8410.537420441873, 8441.746747597583, 5081.695098294114, 5231.869203269911, 5235.397929238148, 5235.869103272411, 5250.264962905197, 3270.640229574745, 3381.509681096252, 3510.2628448025944, 3567.6075631680496, 3572.6070132285427, 1003.7741508160606, 1026.712520494263, 1063.5426766490407, 1078.8543546621263, 1081.2701426537055, 10818.242723009393, 10853.077488413493, 10939.617113400993, 10965.451727413598, 10966.232363734622, 4526.683132180781, 4602.0, 4689.343491911135, 4692.882677933011, 4772.0694464579765, 9338.57976391064, 9494.857577136348, 9528.0, 9540.856887146698, 9549.09283618055, 5209.843704688866, 5213.478652134756, 5236.869078273074, 5238.240455133984, 5244.108501554745, 4220.198162349144, 4231.640310573635, 4510.909781804411, 4769.165396055676, 4798.928016079762, 3684.5630204220374, 3829.965909204499, 3845.3270677631303, 3945.605439456089, 3957.782321972319, 1160.5009845766401, 1271.7790920716031, 1290.4902563487558, 1295.3587973952954, 1299.564645843634, 746.4364404874285, 750.6809605917463, 793.3811626930888, 895.6104094718688, 912.6212621762027, 5633.887322253532, 5685.630434021776, 5775.942240577624, 5814.912776308359, 5877.647341159544, 762.4510352546158, 857.5755001274284, 863.9611217495228, 891.0465801592363, 920.6961702638114, 3018.758499320093, 3646.34365814156, 3970.1662650843646, 4015.3976903464713, 4056.634902858757, 8973.37186396959, 9406.717798466058, 10157.085862272432, 10188.235882308856, 10210.693679189637, 9667.27495437845, 10118.898811011943, 10146.239032072477, 10228.59085636581, 10232.976702329708, 7705.537667739954, 7910.9604451977375, 7922.801929951797, 7943.960280198562, 7945.205479452125, 5759.884802304015, 5785.681787501728, 5807.245058142415, 5848.152017957287, 6022.819315420369, 9193.816123677623, 9312.953435232781, 9416.62333506652, 9450.43297402158, 9482.573284202366, 8857.33569982241, 9654.0, 9676.080772326617, 9677.854832177663, 9678.032196780267, 6589.374009469093, 6822.590644561342, 7080.610566418896, 7216.134063912364, 7351.522151060164, 7875.409344299315, 7969.561674107979, 8073.7577872663915, 8090.312323452573, 8097.433179677366, 7068.469864760266, 7204.53170543913, 7212.350888420067, 7233.276672332725, 7363.852722945619, 6947.722091116501, 7027.789166325018, 7089.893651595127, 7103.573785572882, 7192.496525243392, 6627.569208001464, 6656.36764507371, 6664.366885145903, 6753.628550429774, 6778.288279730557, 4052.0, 4123.36087906375, 4839.516048395132, 4928.334674818853, 4969.105560999054, 4842.685225460334, 4858.076965376567, 4862.708237505761, 4992.176290912104, 5099.490050994871], "value": 4415.721113815946, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.0268346657629, 306.27260256889883, 308.8656434451015, 313.34979916672904, 316.01718654983, 4096.85661001865, 4934.1365261079345, 5115.744212789359, 5178.275041494189, 5201.947980520198, 4873.123847318781, 5015.673981191229, 5044.016416798726, 5060.4686507916595, 5085.593152547796, 3946.842126314944, 3954.5254569451704, 3956.643902048815, 3991.2416640838146, 3993.221321842242, 3165.7942233754848, 3360.4791257355027, 3874.8062596870186, 3980.24375368679, 3985.641292283694, 3347.464405695089, 3518.014955812364, 3806.9721175282657, 3829.8468061277517, 3853.287141878747, 3840.289546433905, 3847.480590120326, 3851.480050193216, 3857.4599556061894, 3887.5334959804723, 3375.8982835854554, 3380.6957373836226, 3392.5080863274934, 3410.488426735985, 3424.434968230239, 4482.551744825524, 4806.807727690888, 4818.975905120487, 4904.651220914241, 4944.90110197794, 3836.462895194675, 4349.543297953701, 4400.031992961543, 4406.3916670415065, 4407.559244075599, 5037.219231019198, 5042.773075211624, 5338.839834804962, 5376.677399356051, 5381.838544843661, 3479.9478007829903, 3627.891163265106, 3723.050622091376, 3727.4408838674144, 3739.513863197767, 4887.7311747853855, 5599.80400685974, 5617.410171931929, 5662.263905692274, 5689.630174038674, 3767.679747221462, 3773.3585290500682, 3783.3000894834545, 3791.4123310886857, 3802.4866643003393, 3258.7881787683723, 3824.7896365700153, 3861.092643228839, 3862.208247309295, 3862.980685096613, 3838.8272527736317, 3997.2205419942834, 4395.384646149541, 4461.590740999889, 4475.70907890986, 4445.2887537993765, 4814.903701926012, 5249.947500525022, 5450.236966824647, 5478.616496845182, 1026.7279171019686, 1214.4534959268365, 1217.3000524698334, 1249.5193196062514, 1264.37413480326, 670.6412069542771, 816.0330008939426, 851.1063383447356, 866.8526350520426, 875.2210532625968, 4132.834686612562, 4193.97903010483, 4888.560029597354, 5008.624353173526, 5085.64400491962, 4425.296260939562, 4642.419697537808, 4800.783964721596, 5418.756155972991, 5447.237386765856, 4151.543330233671, 4946.8763280918265, 4959.892045880486, 5004.047611908991, 5085.0, 2386.809055275574, 2769.6982418263383, 2784.3317603775354, 2809.3819359740824, 2845.8719357628956, 5131.666441681279, 5325.786968521294, 5597.702584592334, 5635.464630860061, 5759.424057594207, 4411.897025743564, 4860.736208585722, 5026.472220416877, 5063.544281014726, 5092.745362731923, 5654.134135735198, 5672.986089938013, 5676.858078547987, 5681.744321505542, 5687.857803554944, 4735.928961065656, 4976.8755781105765, 4980.4272508660515, 5334.67991920486, 5373.247745315737, 5285.207218917193, 5296.510639999195, 5314.601404894571, 5318.521333080042, 5320.920186197132, 4840.370751802243, 5415.702136382379, 5672.773089076556, 5674.687892165971, 5711.457411545896, 2843.4881721290367, 3590.856365745341, 3772.43413487979, 3802.73380863348, 3811.3901775715754, 5599.748011339418, 5603.80386686464, 5606.3461278922005, 5629.802956896653, 5637.774489020393, 5017.197248440232, 5484.698341591251, 5503.229547863222, 5517.668939863621, 5693.4021927696995, 4354.716943398669, 4665.720056796603, 5285.312909321764, 5457.61796674229, 5473.972630136824, 5069.619778516552, 5183.0, 5236.816711415082, 5372.247885296139, 5385.919211211756, 4999.650094474398, 5225.634205605571, 5434.402215756185, 5465.918011229915, 5467.015937131355, 4254.787260637017, 4265.722728022669, 4599.471060827914, 5331.440198779227, 5426.593005524681], "value": 3888.8394762198104, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.420950877491, 694.8749225139479, 756.2059837170971, 762.8359902620936, 778.4122987144705, 14042.929785351072, 14873.256337183138, 14936.103833769981, 14964.7007059859, 15023.0, 13554.593362199124, 13555.728885422286, 13560.98292628052, 13580.456781728719, 13619.863801361984, 658.3613894522317, 684.0594182998377, 817.9550124743137, 826.2274773087153, 848.567230712335, 796.4584082823677, 821.7493664432345, 841.6170642357713, 843.1652663862762, 845.0830848529353, 865.8744482050131, 881.3566096749377, 885.0972008551274, 885.1679421343916, 885.9734207973739, 860.5095095795399, 865.2213008292587, 873.3668090634303, 876.4741155306829, 877.7717793373705, 615.9168512250834, 616.6731632234922, 617.253123720299, 640.8686219325027, 663.7046514301138, 12925.482980680761, 13045.08684392093, 13052.15161014531, 13077.411516481781, 14433.92783036078, 12046.939765301206, 12162.939185304105, 12233.20484168535, 13410.597682069554, 14024.92987535066, 12119.818202726967, 12274.263544187377, 12280.877191228152, 12358.382080895919, 12761.553345632858, 4369.737815731066, 4378.190034843564, 4397.494288156871, 4456.420665313489, 4552.704074235197, 88046.0, 93367.59948600776, 93476.06523934742, 93620.59569106401, 94059.0594094064, 10097.343672661253, 10111.848322275171, 10159.898401016044, 10304.484775761182, 10455.215858810467, 19743.703844442345, 20033.699494507593, 20038.398848034583, 20097.39707808768, 20140.697889531668, 7638.579878106758, 7698.384129269645, 7778.0, 7931.484453510503, 8014.0, 4344.066025804467, 4348.369486424451, 4391.385206071152, 4463.330500424897, 4489.922418619508, 3440.8451619677176, 3500.7024402926036, 3529.170644898447, 3560.2345495718546, 3563.322968635951, 536.2920944353416, 615.8114838362004, 622.6762083716442, 667.4026746062248, 671.9160104986877, 574.5030548575452, 617.8208319587269, 635.7806556737918, 642.9035644653246, 648.7015972652632, 8794.0, 8928.866067009, 9023.954880225558, 9121.270298376114, 9230.218830123342, 562.2657226155778, 604.1089393145076, 643.1992169748693, 664.7341063574609, 666.8432918264205, 4454.888627784331, 4580.702254353457, 4583.876950147235, 4593.470389322757, 4692.225782745809, 111057.0, 114133.0, 115542.0, 116570.0, 116794.24808627878, 102354.46468302982, 103428.96571034344, 105262.0, 106378.0, 106591.0, 90262.0, 91101.63347549792, 92673.5366323164, 94363.52818235866, 94650.52674736582, 8854.867176992222, 8962.372633915564, 8969.0, 8991.595378207865, 9216.723498295061, 46692.7665361671, 48373.27440088331, 56465.15302270387, 59110.704446477495, 60436.0, 67674.66162669155, 81910.59044704976, 84231.0, 85149.0, 85521.57239213763, 11106.0, 11237.831432528683, 11353.943230284127, 11489.0, 11653.359065251492, 12387.504499820267, 12445.315507647167, 12470.189437686522, 12545.184563003375, 12653.87346126527, 11674.24117432398, 11706.297622142698, 11817.468213930222, 11984.340861252365, 12044.75910481768, 11391.886081139082, 11822.645320640395, 11853.051755859684, 12267.263964162179, 12271.07966902468, 11360.1479889007, 11449.885501145216, 11465.31208127515, 11889.048876090064, 12034.398280085785, 9719.0, 9879.703608891745, 9946.602135914773, 10167.288289819642, 10430.37417754937, 9919.950400247953, 10284.537195826355, 10285.382877027103, 10323.741906452398, 10389.168866490512], "value": 7270.344289522094, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.7999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 176.0, 176.0, 133.0, 119.0, 119.0, 115.0, 119.0, 118.0, 117.0], "value": 127.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 116.0, 111.0, 144.0, 96.0, 127.0, 145.0, 125.0, 140.0, 111.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 198.0, 199.0, 200.0, 198.0, 265.0, 253.0, 203.0, 197.0, 197.0], "value": 211.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 83.0, 81.0, 82.0, 61.0, 58.0, 58.0, 61.0, 86.0, 79.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 91.0, 71.0, 69.0, 69.0, 86.0, 69.0, 70.0, 87.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 73.0, 132.0, 85.0, 88.0, 86.0, 89.0, 85.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 128.0, 133.0, 126.0, 92.0, 144.0, 157.0, 108.0, 96.0, 108.0], "value": 118.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 125.0, 78.0, 142.0, 140.0, 95.0, 92.0, 90.0, 90.0], "value": 103.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 59.0, 68.0, 87.0, 92.0, 96.0, 92.0, 93.0, 60.0], "value": 75.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 73.0, 81.0, 75.0, 73.0, 71.0, 72.0, 74.0, 71.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 92.0, 85.0, 87.0, 88.0, 77.0, 95.0, 103.0, 105.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 149.0, 152.0, 146.0, 151.0, 154.0, 160.0, 160.0, 216.0, 185.0], "value": 162.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 173.0, 167.0, 171.0, 168.0, 237.0, 226.0, 188.0, 211.0, 155.0], "value": 186.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 69.0, 69.0, 65.0, 62.0, 79.0, 88.0, 61.0, 77.0], "value": 70.8, "unit": "score"}]}]} 04:15:53 INFO - Return code: 0 04:15:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:15:53 ERROR - # TBPL SUCCESS # 04:15:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:15:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:15:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:15:53 INFO - 2016-12-14 04:15:53.259 screenresolution[1956:21794] starting screenresolution argv=screenresolution get 04:15:53 INFO - 2016-12-14 04:15:53.261 screenresolution[1956:21794] Display 0: 1600x1200x32@60 04:15:53 INFO - 2016-12-14 04:15:53.268 screenresolution[1957:21797] starting screenresolution argv=screenresolution list 04:15:53 INFO - Available Modes on Display 0 04:15:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:15:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:15:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:15:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:15:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:15:53 INFO - Intel Iris: 04:15:53 INFO - Chipset Model: Intel Iris 04:15:53 INFO - Type: GPU 04:15:53 INFO - Bus: Built-In 04:15:53 INFO - VRAM (Dynamic, Max): 1536 MB 04:15:53 INFO - Vendor: Intel (0x8086) 04:15:53 INFO - Device ID: 0x0a2e 04:15:53 INFO - Revision ID: 0x0009 04:15:53 INFO - Displays: 04:15:53 INFO - Display: 04:15:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:15:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:15:53 INFO - Display Serial Number: 1600x1200 60 04:15:53 INFO - Main Display: Yes 04:15:53 INFO - Mirror: Off 04:15:53 INFO - Online: Yes 04:15:53 INFO - Rotation: Supported 04:15:53 INFO - Return code: 0 04:15:53 INFO - Running post-action listener: _resource_record_post_action 04:15:53 INFO - [mozharness: 2016-12-14 12:15:53.508466Z] Finished run-tests step (success) 04:15:53 INFO - Running post-run listener: _resource_record_post_run 04:15:53 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 1613824; Write bytes: 661691904; Read time: 19; Write time: 2817 04:15:53 INFO - TinderboxPrint: CPU usage
54.4% 04:15:53 INFO - TinderboxPrint: I/O read bytes / time
1,613,824 / 19 04:15:53 INFO - TinderboxPrint: I/O write bytes / time
661,691,904 / 2,817 04:15:53 INFO - TinderboxPrint: CPU idle
2,210.9 (45.6%) 04:15:53 INFO - TinderboxPrint: CPU system
979.3 (20.2%) 04:15:53 INFO - TinderboxPrint: CPU user
1,655.5 (34.2%) 04:15:53 INFO - TinderboxPrint: Swap in / out
350,367,744 / 0 04:15:53 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345541120; Write bytes: 345993728; Read time: 26795; Write time: 712 04:15:53 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 1564672; Write bytes: 308509696; Read time: 18; Write time: 2076 04:15:53 INFO - Running post-run listener: _upload_blobber_files 04:15:53 INFO - Blob upload gear active. 04:15:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:15:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:15:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:15:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:15:54 INFO - (blobuploader) - INFO - Open directory for files ... 04:15:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:15:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:15:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:55 INFO - (blobuploader) - INFO - Done attempting. 04:15:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:15:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:15:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:56 INFO - (blobuploader) - INFO - Done attempting. 04:15:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:15:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:15:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:58 INFO - (blobuploader) - INFO - Done attempting. 04:15:58 INFO - (blobuploader) - INFO - Iteration through files over. 04:15:58 INFO - Return code: 0 04:15:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:15:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:15:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb4df34254a515d1be99edfefe53d69773b4717a3887f940255b48448c4e4cbf1fd5efde1318e3659389d03eaba26cfb4337e0536d2f802dd39ffb3de2fb20b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/697c9b2277546388cdba6c19887f04cbf844c787565e09893d5826e3d1d85c0a60cba2f2b733ef57c3b3319b3bee31af3ac3a4ac7d073c8e6c51b99bc5a6f5b2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/70814cc4d740a0ae27db6fa740b9795949d242f6a6a5f480548def22735193140e9df04ae2408c5bc8c769131dae0670fede4e16228f532cdb883c5773247979"} 04:15:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:15:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:15:58 INFO - Contents: 04:15:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb4df34254a515d1be99edfefe53d69773b4717a3887f940255b48448c4e4cbf1fd5efde1318e3659389d03eaba26cfb4337e0536d2f802dd39ffb3de2fb20b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/697c9b2277546388cdba6c19887f04cbf844c787565e09893d5826e3d1d85c0a60cba2f2b733ef57c3b3319b3bee31af3ac3a4ac7d073c8e6c51b99bc5a6f5b2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/70814cc4d740a0ae27db6fa740b9795949d242f6a6a5f480548def22735193140e9df04ae2408c5bc8c769131dae0670fede4e16228f532cdb883c5773247979"} 04:15:58 INFO - Running post-run listener: copy_logs_to_upload_dir 04:15:58 INFO - Copying logs to upload dir... 04:15:58 INFO - mkdir: /builds/slave/test/build/upload/logs 04:15:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.230288 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-12-14 04:15:58.874960) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 04:15:58.878453) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb4df34254a515d1be99edfefe53d69773b4717a3887f940255b48448c4e4cbf1fd5efde1318e3659389d03eaba26cfb4337e0536d2f802dd39ffb3de2fb20b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/697c9b2277546388cdba6c19887f04cbf844c787565e09893d5826e3d1d85c0a60cba2f2b733ef57c3b3319b3bee31af3ac3a4ac7d073c8e6c51b99bc5a6f5b2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/70814cc4d740a0ae27db6fa740b9795949d242f6a6a5f480548def22735193140e9df04ae2408c5bc8c769131dae0670fede4e16228f532cdb883c5773247979"} build_url:https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011208 build_url: 'https://queue.taskcluster.net/v1/task/CUparYOjS6u1fPbxksE9ew/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb4df34254a515d1be99edfefe53d69773b4717a3887f940255b48448c4e4cbf1fd5efde1318e3659389d03eaba26cfb4337e0536d2f802dd39ffb3de2fb20b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/697c9b2277546388cdba6c19887f04cbf844c787565e09893d5826e3d1d85c0a60cba2f2b733ef57c3b3319b3bee31af3ac3a4ac7d073c8e6c51b99bc5a6f5b2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/70814cc4d740a0ae27db6fa740b9795949d242f6a6a5f480548def22735193140e9df04ae2408c5bc8c769131dae0670fede4e16228f532cdb883c5773247979"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 04:15:58.911822) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 04:15:58.912147) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x8X4D3pe4Y/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Tm7ZRswndE/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005300 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 04:15:58.960668) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 04:15:58.960970) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 04:15:58.961328) ========= ========= Total master_lag: 0.18 =========