builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0227 starttime: 1475284448.22 results: success (0) buildid: 20160930152455 builduid: 35007191263e4a839a1c2826b0f447d6 revision: ac89a649cbb96c6e50446b0df901e9d7d8963f24 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-30 18:14:08.223373) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-30 18:14:08.223814) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 18:14:08.224107) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 18:14:08.248287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 18:14:08.248628) ========= 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.fy7HxJy5Pd/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.yYLizVV8IC/Listeners TMPDIR=/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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-09-30 18:14:08-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.52M=0.001s 2016-09-30 18:14:08 (8.52 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.604116 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 18:14:08.927967) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 18:14:08.928314) ========= 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.fy7HxJy5Pd/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.yYLizVV8IC/Listeners TMPDIR=/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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.019511 ========= master_lag: 0.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 18:14:09.033136) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 18:14:09.033499) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev ac89a649cbb96c6e50446b0df901e9d7d8963f24 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev ac89a649cbb96c6e50446b0df901e9d7d8963f24 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fy7HxJy5Pd/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.yYLizVV8IC/Listeners TMPDIR=/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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-09-30 18:14:09,121 truncating revision to first 12 chars 2016-09-30 18:14:09,121 Setting DEBUG logging. 2016-09-30 18:14:09,121 attempt 1/10 2016-09-30 18:14:09,121 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/ac89a649cbb9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-30 18:14:09,790 unpacking tar archive at: autoland-ac89a649cbb9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.957818 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 18:14:10.023881) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 18:14:10.024205) ========= script_repo_revision: ac89a649cbb96c6e50446b0df901e9d7d8963f24 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 18:14:10.024645) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 18:14:10.024987) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 18:14:10.047456) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-09-30 18:14:10.047741) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fy7HxJy5Pd/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.yYLizVV8IC/Listeners TMPDIR=/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 18:14:10 INFO - MultiFileLogger online at 20160930 18:14:10 in /builds/slave/test 18:14:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 18:14:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:14:10 INFO - {'append_to_log': False, 18:14:10 INFO - 'base_work_dir': '/builds/slave/test', 18:14:10 INFO - 'blob_upload_branch': 'Autoland', 18:14:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:14:10 INFO - 'branch': 'Autoland', 18:14:10 INFO - 'buildbot_json_path': 'buildprops.json', 18:14:10 INFO - 'config_files': ('talos/mac_config.py',), 18:14:10 INFO - 'default_actions': ('clobber', 18:14:10 INFO - 'read-buildbot-config', 18:14:10 INFO - 'download-and-extract', 18:14:10 INFO - 'populate-webroot', 18:14:10 INFO - 'create-virtualenv', 18:14:10 INFO - 'install', 18:14:10 INFO - 'run-tests'), 18:14:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:14:10 INFO - 'download_minidump_stackwalk': True, 18:14:10 INFO - 'download_symbols': 'ondemand', 18:14:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:14:10 INFO - 'tooltool.py': '/tools/tooltool.py', 18:14:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:14:10 INFO - '/tools/misc-python/virtualenv.py')}, 18:14:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:14:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:14:10 INFO - 'installer_path': 'installer.exe', 18:14:10 INFO - 'log_level': 'info', 18:14:10 INFO - 'log_name': 'talos', 18:14:10 INFO - 'log_to_console': True, 18:14:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:14:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:14:10 INFO - 'opt_config_files': (), 18:14:10 INFO - 'pip_index': False, 18:14:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:14:10 INFO - 'cmd': ('bash', 18:14:10 INFO - '-c', 18:14:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:14:10 INFO - 'enabled': True, 18:14:10 INFO - 'halt_on_failure': False, 18:14:10 INFO - 'name': 'check_screen_resolution'},), 18:14:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:14:10 INFO - 'cmd': ('bash', 18:14:10 INFO - '-c', 18:14:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:14:10 INFO - 'enabled': True, 18:14:10 INFO - 'halt_on_failure': False, 18:14:10 INFO - 'name': 'check_screen_resolution'},), 18:14:10 INFO - 'run_cmd_checks_enabled': True, 18:14:10 INFO - 'sps_profile': False, 18:14:10 INFO - 'sps_profile_interval': 0, 18:14:10 INFO - 'suite': 'dromaeojs-e10s', 18:14:10 INFO - 'system_bits': '32', 18:14:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:14:10 INFO - 'title': 't-yosemite-r7-0227', 18:14:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:14:10 INFO - 'use_talos_json': True, 18:14:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:14:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:14:10 INFO - 'work_dir': 'build'} 18:14:10 INFO - [mozharness: 2016-10-01 01:14:10.255192Z] Running clobber step. 18:14:10 INFO - Running pre-action listener: _resource_record_pre_action 18:14:10 INFO - Running main action method: clobber 18:14:10 INFO - rmtree: /builds/slave/test/build 18:14:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:14:11 INFO - Running post-action listener: _resource_record_post_action 18:14:11 INFO - [mozharness: 2016-10-01 01:14:11.870117Z] Finished clobber step (success) 18:14:11 INFO - [mozharness: 2016-10-01 01:14:11.870368Z] Running read-buildbot-config step. 18:14:11 INFO - Running pre-action listener: _resource_record_pre_action 18:14:11 INFO - Running main action method: read_buildbot_config 18:14:11 INFO - Using buildbot properties: 18:14:11 INFO - { 18:14:11 INFO - "project": "", 18:14:11 INFO - "product": "firefox", 18:14:11 INFO - "script_repo_revision": "production", 18:14:11 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 18:14:11 INFO - "repository": "", 18:14:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 18:14:11 INFO - "buildid": "20160930152455", 18:14:11 INFO - "pgo_build": "False", 18:14:11 INFO - "basedir": "/builds/slave/test", 18:14:11 INFO - "buildnumber": 226, 18:14:11 INFO - "slavename": "t-yosemite-r7-0227", 18:14:11 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:14:11 INFO - "platform": "yosemite_r7", 18:14:11 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 18:14:11 INFO - "branch": "autoland", 18:14:11 INFO - "repo_path": "integration/autoland", 18:14:11 INFO - "revision": "ac89a649cbb96c6e50446b0df901e9d7d8963f24", 18:14:11 INFO - "stage_platform": "macosx64", 18:14:11 INFO - "builduid": "35007191263e4a839a1c2826b0f447d6", 18:14:11 INFO - "slavebuilddir": "test" 18:14:11 INFO - } 18:14:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 18:14:11 INFO - Running post-action listener: _resource_record_post_action 18:14:11 INFO - [mozharness: 2016-10-01 01:14:11.876104Z] Finished read-buildbot-config step (success) 18:14:11 INFO - [mozharness: 2016-10-01 01:14:11.876327Z] Running download-and-extract step. 18:14:11 INFO - Running pre-action listener: _resource_record_pre_action 18:14:11 INFO - Running main action method: download_and_extract 18:14:11 INFO - mkdir: /builds/slave/test/build/tests 18:14:11 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')]} 18:14:11 INFO - trying https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 18:14:11 INFO - Downloading https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 18:14:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 18:14:12 INFO - Downloaded 1199 bytes. 18:14:12 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 18:14:12 INFO - Using the following test package requirements: 18:14:12 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 18:14:12 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:14:12 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 18:14:12 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:14:12 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 18:14:12 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:14:12 INFO - u'jsshell-mac.zip'], 18:14:12 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:14:12 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 18:14:12 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 18:14:12 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:14:12 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 18:14:12 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:14:12 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 18:14:12 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:14:12 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 18:14:12 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:14:12 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 18:14:12 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:14:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 18:14:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:14:12 INFO - Fetch https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 18:14:13 INFO - Http code: 200 18:14:13 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 18:14:13 INFO - x-amz-cf-id: 4EtNDKMnB9KeLfEDL9XJDmnd4FW7cSUmGs6IkAVOa03573XzD2HGDA== 18:14:13 INFO - x-amz-version-id: RFuEKvlY3naW2.nsiMPq3RpZQnaEFLwI 18:14:13 INFO - Expected file size: 16473436 18:14:13 INFO - Obtained file size: 16473436 18:14:20 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:14:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 18:14:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:14:20 INFO - Fetch https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 18:14:20 INFO - Http code: 200 18:14:20 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 18:14:20 INFO - x-amz-cf-id: 0gl4CJyAGuIlV7Mv0yT4RWihQ85NPf33VLgL9DVJQslcmnXWehXZxQ== 18:14:20 INFO - x-amz-version-id: RFuEKvlY3naW2.nsiMPq3RpZQnaEFLwI 18:14:21 INFO - Expected file size: 16473436 18:14:21 INFO - Obtained file size: 16473436 18:14:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 18:14:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:14:27 INFO - Fetch https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 18:14:28 INFO - Http code: 200 18:14:28 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 18:14:28 INFO - x-amz-cf-id: -66pEA0rOhVY4aCeCKeUXQDWUvYUTaKmJ0PMND01zwjkAvzqvXlynQ== 18:14:28 INFO - x-amz-version-id: wGE9Ld3s_GfudNBypVaUpBgeyfhY2kb9 18:14:28 INFO - Expected file size: 14408522 18:14:28 INFO - Obtained file size: 14408522 18:14:29 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')]} 18:14:29 INFO - trying https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 18:14:29 INFO - Downloading https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 18:14:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 18:14:35 INFO - Downloaded 111779139 bytes. 18:14:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 18:14:35 INFO - mkdir: /builds/slave/test/properties 18:14:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:14:35 INFO - Writing to file /builds/slave/test/properties/build_url 18:14:35 INFO - Contents: 18:14:35 INFO - build_url:https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 18:14:35 INFO - Running post-action listener: _resource_record_post_action 18:14:35 INFO - Running post-action listener: set_extra_try_arguments 18:14:35 INFO - [mozharness: 2016-10-01 01:14:35.821979Z] Finished download-and-extract step (success) 18:14:35 INFO - [mozharness: 2016-10-01 01:14:35.822133Z] Running populate-webroot step. 18:14:35 INFO - Running pre-action listener: _resource_record_pre_action 18:14:35 INFO - Running main action method: populate_webroot 18:14:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:14:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:14:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:14:35 INFO - '--tppagecycles', 18:14:35 INFO - '7'], 18:14:35 INFO - 'tests': ['tsvgm']}}, 18:14:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:14:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:14:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:14:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:14:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:14:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:14:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:35 INFO - 'talos_options': ['--disable-e10s'], 18:14:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:14:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:14:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:35 INFO - 'talos_options': ['--disable-e10s'], 18:14:35 INFO - 'tests': ['damp', 'tps']}, 18:14:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:35 INFO - 'tests': ['damp', 'tps']}, 18:14:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:14:35 INFO - 'tests': ['dromaeo_dom']}, 18:14:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:14:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:14:35 INFO - 'tests': ['basic_compositor_video']}, 18:14:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:14:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:14:35 INFO - 'tests': ['a11yr', 18:14:35 INFO - 'ts_paint', 18:14:35 INFO - 'tpaint', 18:14:35 INFO - 'sessionrestore', 18:14:35 INFO - 'sessionrestore_no_auto_restore', 18:14:35 INFO - 'tabpaint']}, 18:14:35 INFO - 'other-e10s': {'tests': ['a11yr', 18:14:35 INFO - 'ts_paint', 18:14:35 INFO - 'tpaint', 18:14:35 INFO - 'sessionrestore', 18:14:35 INFO - 'sessionrestore_no_auto_restore', 18:14:35 INFO - 'tabpaint']}, 18:14:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:14:35 INFO - 'ts_paint', 18:14:35 INFO - 'tpaint', 18:14:35 INFO - 'sessionrestore', 18:14:35 INFO - 'sessionrestore_no_auto_restore', 18:14:35 INFO - 'tabpaint']}, 18:14:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:14:35 INFO - 'ts_paint', 18:14:35 INFO - 'tpaint', 18:14:35 INFO - 'sessionrestore', 18:14:35 INFO - 'sessionrestore_no_auto_restore', 18:14:35 INFO - 'tabpaint']}, 18:14:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:14:35 INFO - 'tests': ['a11yr', 18:14:35 INFO - 'ts_paint', 18:14:35 INFO - 'tpaint', 18:14:35 INFO - 'sessionrestore', 18:14:35 INFO - 'sessionrestore_no_auto_restore', 18:14:35 INFO - 'tabpaint']}, 18:14:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:14:35 INFO - 'tests': ['a11yr', 18:14:35 INFO - 'ts_paint', 18:14:35 INFO - 'tpaint', 18:14:35 INFO - 'sessionrestore', 18:14:35 INFO - 'sessionrestore_no_auto_restore', 18:14:35 INFO - 'tabpaint']}, 18:14:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:14:35 INFO - 'tests': ['tsvgx', 18:14:35 INFO - 'tsvgr_opacity', 18:14:35 INFO - 'tart', 18:14:35 INFO - 'tscrollx', 18:14:35 INFO - 'cart']}, 18:14:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:14:35 INFO - 'tsvgr_opacity', 18:14:35 INFO - 'tart', 18:14:35 INFO - 'tscrollx', 18:14:35 INFO - 'cart']}, 18:14:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:35 INFO - 'talos_options': ['--disable-e10s'], 18:14:35 INFO - 'tests': ['tp5o']}, 18:14:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:35 INFO - 'tests': ['tp5o']}, 18:14:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:35 INFO - 'talos_options': ['--disable-e10s', 18:14:35 INFO - '--xperf_path', 18:14:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:14:35 INFO - 'tests': ['tp5n']}, 18:14:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:35 INFO - 'talos_options': ['--xperf_path', 18:14:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:14:35 INFO - 'tests': ['tp5n']}}, 18:14:35 INFO - 'talos.zip': {'path': '', 18:14:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:14:35 INFO - Running post-action listener: _resource_record_post_action 18:14:35 INFO - [mozharness: 2016-10-01 01:14:35.840519Z] Finished populate-webroot step (success) 18:14:35 INFO - [mozharness: 2016-10-01 01:14:35.840632Z] Running create-virtualenv step. 18:14:35 INFO - Running pre-action listener: _resource_record_pre_action 18:14:35 INFO - Running main action method: create_virtualenv 18:14:35 INFO - Creating virtualenv /builds/slave/test/build/venv 18:14:35 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 18:14:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:14:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:14:35 INFO - Using real prefix '/tools/python27' 18:14:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:14:36 INFO - Installing distribute.............................................................................................................................................................................................done. 18:14:39 INFO - Installing pip.................done. 18:14:39 INFO - Return code: 0 18:14:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:14:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:14:39 INFO - Reading from file tmpfile_stdout 18:14:39 INFO - Output received: 18:14:39 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) 18:14:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:14:39 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')]} 18:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:39 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')]} 18:14:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:39 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 0x1079f5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10796b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f858bdd51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107809d30>, '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 0x10762fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107801b20>, '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.fy7HxJy5Pd/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.yYLizVV8IC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 18:14:39 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 18:14:39 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 18:14:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fy7HxJy5Pd/Render', 18:14:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:39 INFO - 'HOME': '/Users/cltbld', 18:14:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:39 INFO - 'LOGNAME': 'cltbld', 18:14:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:39 INFO - 'MOZ_NO_REMOTE': '1', 18:14:39 INFO - 'NO_EM_RESTART': '1', 18:14:39 INFO - 'PAGER': '/bin/cat', 18:14:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:39 INFO - 'PWD': '/builds/slave/test', 18:14:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:39 INFO - 'SHELL': '/bin/bash', 18:14:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYLizVV8IC/Listeners', 18:14:39 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 18:14:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:39 INFO - 'USER': 'cltbld', 18:14:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:39 INFO - 'XPC_FLAGS': '0x0', 18:14:39 INFO - 'XPC_SERVICE_NAME': '0', 18:14:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:40 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 18:14:40 INFO - Cleaning up... 18:14:40 INFO - Return code: 0 18:14:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:14:40 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')]} 18:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:40 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')]} 18:14:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:40 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 0x1079f5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10796b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f858bdd51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107809d30>, '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 0x10762fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107801b20>, '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.fy7HxJy5Pd/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.yYLizVV8IC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 18:14:40 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 18:14:40 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 18:14:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fy7HxJy5Pd/Render', 18:14:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:40 INFO - 'HOME': '/Users/cltbld', 18:14:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:40 INFO - 'LOGNAME': 'cltbld', 18:14:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:40 INFO - 'MOZ_NO_REMOTE': '1', 18:14:40 INFO - 'NO_EM_RESTART': '1', 18:14:40 INFO - 'PAGER': '/bin/cat', 18:14:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:40 INFO - 'PWD': '/builds/slave/test', 18:14:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:40 INFO - 'SHELL': '/bin/bash', 18:14:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYLizVV8IC/Listeners', 18:14:40 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 18:14:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:40 INFO - 'USER': 'cltbld', 18:14:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:40 INFO - 'XPC_FLAGS': '0x0', 18:14:40 INFO - 'XPC_SERVICE_NAME': '0', 18:14:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:40 INFO - Downloading/unpacking psutil>=3.1.1 18:14:40 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 18:14:40 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 18:14:40 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 18:14:40 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 18:14:40 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 18:14:40 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 18:14:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:14:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:14:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:14:44 INFO - Installing collected packages: psutil 18:14:44 INFO - Running setup.py install for psutil 18:14:44 INFO - building 'psutil._psutil_osx' extension 18:14:44 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 18:14:44 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 18:14:44 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 18:14:44 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 18:14:44 INFO - building 'psutil._psutil_posix' extension 18:14:44 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 18:14:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:14:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:14:44 INFO - ^ 18:14:44 INFO - 1 warning generated. 18:14:44 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 18:14:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:14:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:14:45 INFO - Successfully installed psutil 18:14:45 INFO - Cleaning up... 18:14:45 INFO - Return code: 0 18:14:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:14:45 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')]} 18:14:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:45 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')]} 18:14:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:45 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 0x1079f5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10796b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f858bdd51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107809d30>, '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 0x10762fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107801b20>, '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.fy7HxJy5Pd/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.yYLizVV8IC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 18:14:45 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 18:14:45 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 18:14:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fy7HxJy5Pd/Render', 18:14:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:45 INFO - 'HOME': '/Users/cltbld', 18:14:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:45 INFO - 'LOGNAME': 'cltbld', 18:14:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:45 INFO - 'MOZ_NO_REMOTE': '1', 18:14:45 INFO - 'NO_EM_RESTART': '1', 18:14:45 INFO - 'PAGER': '/bin/cat', 18:14:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:45 INFO - 'PWD': '/builds/slave/test', 18:14:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:45 INFO - 'SHELL': '/bin/bash', 18:14:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYLizVV8IC/Listeners', 18:14:45 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 18:14:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:45 INFO - 'USER': 'cltbld', 18:14:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:45 INFO - 'XPC_FLAGS': '0x0', 18:14:45 INFO - 'XPC_SERVICE_NAME': '0', 18:14:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:14:45 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 18:14:45 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 18:14:45 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 18:14:45 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 18:14:45 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 18:14:45 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 18:14:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:14:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:14:47 INFO - Installing collected packages: mozsystemmonitor 18:14:47 INFO - Running setup.py install for mozsystemmonitor 18:14:48 INFO - Successfully installed mozsystemmonitor 18:14:48 INFO - Cleaning up... 18:14:48 INFO - Return code: 0 18:14:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:14:48 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')]} 18:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:48 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')]} 18:14:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:48 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 0x1079f5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10796b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f858bdd51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107809d30>, '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 0x10762fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107801b20>, '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.fy7HxJy5Pd/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.yYLizVV8IC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 18:14:48 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 18:14:48 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 18:14:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fy7HxJy5Pd/Render', 18:14:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:48 INFO - 'HOME': '/Users/cltbld', 18:14:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:48 INFO - 'LOGNAME': 'cltbld', 18:14:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:48 INFO - 'MOZ_NO_REMOTE': '1', 18:14:48 INFO - 'NO_EM_RESTART': '1', 18:14:48 INFO - 'PAGER': '/bin/cat', 18:14:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:48 INFO - 'PWD': '/builds/slave/test', 18:14:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:48 INFO - 'SHELL': '/bin/bash', 18:14:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYLizVV8IC/Listeners', 18:14:48 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 18:14:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:48 INFO - 'USER': 'cltbld', 18:14:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:48 INFO - 'XPC_FLAGS': '0x0', 18:14:48 INFO - 'XPC_SERVICE_NAME': '0', 18:14:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:48 INFO - Downloading/unpacking jsonschema==2.5.1 18:14:48 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 18:14:48 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 18:14:48 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 18:14:48 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 18:14:48 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 18:14:48 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 18:14:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:14:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:14:50 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 18:14:50 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 18:14:50 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 18:14:50 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 18:14:50 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 18:14:50 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 18:14:51 INFO - Downloading functools32-3.2.3-2.tar.gz 18:14:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:14:51 INFO - warning: no files found matching '*.txt' 18:14:51 INFO - no previously-included directories found matching 'build' 18:14:51 INFO - no previously-included directories found matching 'dist' 18:14:51 INFO - no previously-included directories found matching '.git*' 18:14:51 INFO - Installing collected packages: jsonschema, functools32 18:14:51 INFO - Running setup.py install for functools32 18:14:51 INFO - warning: no files found matching '*.txt' 18:14:51 INFO - no previously-included directories found matching 'build' 18:14:51 INFO - no previously-included directories found matching 'dist' 18:14:51 INFO - no previously-included directories found matching '.git*' 18:14:51 INFO - Successfully installed jsonschema functools32 18:14:51 INFO - Cleaning up... 18:14:51 INFO - Return code: 0 18:14:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:14:51 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')]} 18:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:51 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')]} 18:14:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:51 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 0x1079f5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10796b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f858bdd51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107809d30>, '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 0x10762fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107801b20>, '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.fy7HxJy5Pd/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.yYLizVV8IC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 18:14:51 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 18:14:51 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 18:14:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fy7HxJy5Pd/Render', 18:14:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:51 INFO - 'HOME': '/Users/cltbld', 18:14:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:51 INFO - 'LOGNAME': 'cltbld', 18:14:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:51 INFO - 'MOZ_NO_REMOTE': '1', 18:14:51 INFO - 'NO_EM_RESTART': '1', 18:14:51 INFO - 'PAGER': '/bin/cat', 18:14:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:51 INFO - 'PWD': '/builds/slave/test', 18:14:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:51 INFO - 'SHELL': '/bin/bash', 18:14:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYLizVV8IC/Listeners', 18:14:51 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 18:14:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:51 INFO - 'USER': 'cltbld', 18:14:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:51 INFO - 'XPC_FLAGS': '0x0', 18:14:51 INFO - 'XPC_SERVICE_NAME': '0', 18:14:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:14:51 INFO - Cleaning up... 18:14:51 INFO - Return code: 0 18:14:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:14:51 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')]} 18:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:51 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')]} 18:14:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:51 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 0x1079f5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10796b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f858bdd51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107809d30>, '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 0x10762fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107801b20>, '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.fy7HxJy5Pd/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.yYLizVV8IC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 18:14:51 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 18:14:51 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 18:14:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fy7HxJy5Pd/Render', 18:14:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:51 INFO - 'HOME': '/Users/cltbld', 18:14:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:51 INFO - 'LOGNAME': 'cltbld', 18:14:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:51 INFO - 'MOZ_NO_REMOTE': '1', 18:14:51 INFO - 'NO_EM_RESTART': '1', 18:14:51 INFO - 'PAGER': '/bin/cat', 18:14:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:51 INFO - 'PWD': '/builds/slave/test', 18:14:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:51 INFO - 'SHELL': '/bin/bash', 18:14:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYLizVV8IC/Listeners', 18:14:51 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 18:14:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:51 INFO - 'USER': 'cltbld', 18:14:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:51 INFO - 'XPC_FLAGS': '0x0', 18:14:51 INFO - 'XPC_SERVICE_NAME': '0', 18:14:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:52 INFO - Downloading/unpacking blobuploader==1.2.4 18:14:52 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 18:14:52 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 18:14:52 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 18:14:52 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 18:14:52 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 18:14:52 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 18:14:54 INFO - Downloading blobuploader-1.2.4.tar.gz 18:14:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:14:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:14:54 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 18:14:54 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 18:14:54 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 18:14:54 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 18:14:54 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 18:14:54 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 18:14:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:14:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:14:55 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 18:14:55 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 18:14:55 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 18:14:55 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 18:14:55 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 18:14:55 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 18:14:55 INFO - Downloading docopt-0.6.1.tar.gz 18:14:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:14:55 INFO - Installing collected packages: blobuploader, requests, docopt 18:14:55 INFO - Running setup.py install for blobuploader 18:14:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:14:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:14:56 INFO - Running setup.py install for requests 18:14:56 INFO - Running setup.py install for docopt 18:14:56 INFO - Successfully installed blobuploader requests docopt 18:14:56 INFO - Cleaning up... 18:14:56 INFO - Return code: 0 18:14:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14:56 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')]} 18:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:56 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')]} 18:14:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:56 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 0x1079f5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10796b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f858bdd51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107809d30>, '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 0x10762fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107801b20>, '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.fy7HxJy5Pd/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.yYLizVV8IC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 18:14:56 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 18:14:56 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 18:14:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fy7HxJy5Pd/Render', 18:14:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:56 INFO - 'HOME': '/Users/cltbld', 18:14:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:56 INFO - 'LOGNAME': 'cltbld', 18:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:56 INFO - 'MOZ_NO_REMOTE': '1', 18:14:56 INFO - 'NO_EM_RESTART': '1', 18:14:56 INFO - 'PAGER': '/bin/cat', 18:14:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:56 INFO - 'PWD': '/builds/slave/test', 18:14:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:56 INFO - 'SHELL': '/bin/bash', 18:14:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYLizVV8IC/Listeners', 18:14:56 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 18:14:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:56 INFO - 'USER': 'cltbld', 18:14:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:56 INFO - 'XPC_FLAGS': '0x0', 18:14:56 INFO - 'XPC_SERVICE_NAME': '0', 18:14:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:14:57 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-b2Y4iD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:14:57 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-einfd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:14:57 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-5nBpPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:14:57 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-aqVhUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:14:57 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-LOn_NP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:14:57 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-0zoNMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:14:57 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-j2dO5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:14:57 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-0C7ckv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:14:57 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-aVGZza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:14:57 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-2vVsWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:14:58 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-qSwNwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:14:58 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-Ke8M0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:14:58 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-dd33oV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:14:58 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-jG7cJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:58 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-BtXzfU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:14:58 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-T7eq3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:14:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:14:58 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-IM3g2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:14:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:14:58 INFO - Running setup.py install for manifestparser 18:14:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:14:58 INFO - Running setup.py install for mozcrash 18:14:59 INFO - Running setup.py install for mozdebug 18:14:59 INFO - Running setup.py install for mozdevice 18:14:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:14:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:14:59 INFO - Running setup.py install for mozfile 18:14:59 INFO - Running setup.py install for mozhttpd 18:14:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:14:59 INFO - Running setup.py install for mozinfo 18:14:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:14:59 INFO - Running setup.py install for mozInstall 18:14:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:14:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:14:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:14:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:14:59 INFO - Running setup.py install for mozleak 18:15:00 INFO - Running setup.py install for mozlog 18:15:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:15:00 INFO - Running setup.py install for moznetwork 18:15:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:15:00 INFO - Running setup.py install for mozprocess 18:15:00 INFO - Running setup.py install for mozprofile 18:15:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:15:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:15:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:15:00 INFO - Running setup.py install for mozrunner 18:15:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:15:00 INFO - Running setup.py install for mozscreenshot 18:15:01 INFO - Running setup.py install for moztest 18:15:01 INFO - Running setup.py install for mozversion 18:15:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:15:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:15:01 INFO - Cleaning up... 18:15:01 INFO - Return code: 0 18:15:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:15:01 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')]} 18:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15:01 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')]} 18:15:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15:01 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 0x1079f5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10796b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f858bdd51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107809d30>, '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 0x10762fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107801b20>, '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.fy7HxJy5Pd/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.yYLizVV8IC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 18:15:01 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 18:15:01 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 18:15:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fy7HxJy5Pd/Render', 18:15:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:15:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:15:01 INFO - 'HOME': '/Users/cltbld', 18:15:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:15:01 INFO - 'LOGNAME': 'cltbld', 18:15:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:01 INFO - 'MOZ_NO_REMOTE': '1', 18:15:01 INFO - 'NO_EM_RESTART': '1', 18:15:01 INFO - 'PAGER': '/bin/cat', 18:15:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:15:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:01 INFO - 'PWD': '/builds/slave/test', 18:15:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:15:01 INFO - 'SHELL': '/bin/bash', 18:15:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYLizVV8IC/Listeners', 18:15:01 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 18:15:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:15:01 INFO - 'USER': 'cltbld', 18:15:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:15:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:15:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:01 INFO - 'XPC_FLAGS': '0x0', 18:15:01 INFO - 'XPC_SERVICE_NAME': '0', 18:15:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:15:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:15:01 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-o899mk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:15:01 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)) 18:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:15:01 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-IJBHun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:15:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 18:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:15:01 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-0Y9C0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:15:01 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)) 18:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:15:01 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-OVbnku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:15:02 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)) 18:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:15:02 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-H3p6x1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:15:02 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)) 18:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:15:02 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-3NK5RG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:15:02 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)) 18:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:15:02 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-MO1SCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:15:02 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)) 18:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:15:02 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-hQpPU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:15:02 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)) 18:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:15:02 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-e5gDBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:15:02 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)) 18:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:15:02 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-PRnhAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:15:02 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)) 18:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:15:02 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-WO7CK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:15:02 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)) 18:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:15:02 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-kKOnzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:15:02 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)) 18:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:15:02 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-HD7a3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:15:03 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)) 18:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:15:03 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-gQXxtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:15:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 18:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:15:03 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-ehCrRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:15:03 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)) 18:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:15:03 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-6JiYzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:15:03 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)) 18:15:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:15:03 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-jqi9RZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:15:03 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)) 18:15:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:15:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:15:03 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)) 18:15:03 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)) 18:15:03 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)) 18:15:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:15:03 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 18:15:03 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 18:15:03 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 18:15:03 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 18:15:03 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 18:15:03 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 18:15:05 INFO - Downloading blessings-1.6.tar.gz 18:15:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:15:06 INFO - Installing collected packages: blessings 18:15:06 INFO - Running setup.py install for blessings 18:15:06 INFO - Successfully installed blessings 18:15:06 INFO - Cleaning up... 18:15:06 INFO - Return code: 0 18:15:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:15:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:15:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:15:06 INFO - Reading from file tmpfile_stdout 18:15:06 INFO - Current package versions: 18:15:06 INFO - blessings == 1.6 18:15:06 INFO - blobuploader == 1.2.4 18:15:06 INFO - docopt == 0.6.1 18:15:06 INFO - functools32 == 3.2.3-2 18:15:06 INFO - jsonschema == 2.5.1 18:15:06 INFO - manifestparser == 1.1 18:15:06 INFO - mozInstall == 1.12 18:15:06 INFO - mozcrash == 0.17 18:15:06 INFO - mozdebug == 0.1 18:15:06 INFO - mozdevice == 0.48 18:15:06 INFO - mozfile == 1.2 18:15:06 INFO - mozhttpd == 0.7 18:15:06 INFO - mozinfo == 0.9 18:15:06 INFO - mozleak == 0.1 18:15:06 INFO - mozlog == 3.4 18:15:06 INFO - moznetwork == 0.27 18:15:06 INFO - mozprocess == 0.23 18:15:06 INFO - mozprofile == 0.28 18:15:06 INFO - mozrunner == 6.12 18:15:06 INFO - mozscreenshot == 0.1 18:15:06 INFO - mozsystemmonitor == 0.3 18:15:06 INFO - moztest == 0.7 18:15:06 INFO - mozversion == 1.4 18:15:06 INFO - psutil == 3.1.1 18:15:06 INFO - requests == 1.2.3 18:15:06 INFO - wsgiref == 0.1.2 18:15:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:15: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')]} 18:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15: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')]} 18:15:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15:06 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 0x1079f5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10796b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f858bdd51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107809d30>, '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 0x10762fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107801b20>, '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.fy7HxJy5Pd/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.yYLizVV8IC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 18:15:06 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 18:15:06 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 18:15:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fy7HxJy5Pd/Render', 18:15:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:15:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:15:06 INFO - 'HOME': '/Users/cltbld', 18:15:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:15:06 INFO - 'LOGNAME': 'cltbld', 18:15:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:06 INFO - 'MOZ_NO_REMOTE': '1', 18:15:06 INFO - 'NO_EM_RESTART': '1', 18:15:06 INFO - 'PAGER': '/bin/cat', 18:15:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:15:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:06 INFO - 'PWD': '/builds/slave/test', 18:15:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:15:06 INFO - 'SHELL': '/bin/bash', 18:15:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYLizVV8IC/Listeners', 18:15:06 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 18:15:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:15:06 INFO - 'USER': 'cltbld', 18:15:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:15:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:15:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:06 INFO - 'XPC_FLAGS': '0x0', 18:15:06 INFO - 'XPC_SERVICE_NAME': '0', 18:15:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:15:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:06 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)) 18:15:06 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)) 18:15:06 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)) 18:15:06 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)) 18:15:06 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)) 18:15:06 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)) 18:15:06 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)) 18:15:06 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)) 18:15:06 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)) 18:15:06 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)) 18:15:06 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)) 18:15:06 INFO - Cleaning up... 18:15:06 INFO - Return code: 0 18:15:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:15: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')]} 18:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15: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')]} 18:15:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15:06 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 0x1079f5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10796b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f858bdd51f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107809d30>, '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 0x10762fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107801b20>, '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.fy7HxJy5Pd/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.yYLizVV8IC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 18:15:06 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 18:15:06 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 18:15:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fy7HxJy5Pd/Render', 18:15:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:15:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:15:06 INFO - 'HOME': '/Users/cltbld', 18:15:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:15:06 INFO - 'LOGNAME': 'cltbld', 18:15:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:06 INFO - 'MOZ_NO_REMOTE': '1', 18:15:06 INFO - 'NO_EM_RESTART': '1', 18:15:06 INFO - 'PAGER': '/bin/cat', 18:15:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:15:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:06 INFO - 'PWD': '/builds/slave/test', 18:15:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:15:06 INFO - 'SHELL': '/bin/bash', 18:15:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYLizVV8IC/Listeners', 18:15:06 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 18:15:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:15:06 INFO - 'USER': 'cltbld', 18:15:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:15:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:15:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:06 INFO - 'XPC_FLAGS': '0x0', 18:15:06 INFO - 'XPC_SERVICE_NAME': '0', 18:15:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:15:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:15:07 INFO - Cleaning up... 18:15:07 INFO - Return code: 0 18:15:07 INFO - Running post-action listener: _resource_record_post_action 18:15:07 INFO - Running post-action listener: _start_resource_monitoring 18:15:07 INFO - Starting resource monitoring. 18:15:07 INFO - [mozharness: 2016-10-01 01:15:07.109169Z] Finished create-virtualenv step (success) 18:15:07 INFO - [mozharness: 2016-10-01 01:15:07.110194Z] Running install step. 18:15:07 INFO - Running pre-action listener: _resource_record_pre_action 18:15:07 INFO - Running main action method: install 18:15:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:15:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:15:07 INFO - Reading from file tmpfile_stdout 18:15:07 INFO - Detecting whether we're running mozinstall >=1.0... 18:15:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:15:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:15:07 INFO - Reading from file tmpfile_stdout 18:15:07 INFO - Output received: 18:15:07 INFO - Usage: mozinstall [options] installer 18:15:07 INFO - Options: 18:15:07 INFO - -h, --help show this help message and exit 18:15:07 INFO - -d DEST, --destination=DEST 18:15:07 INFO - Directory to install application into. [default: 18:15:07 INFO - "/builds/slave/test"] 18:15:07 INFO - --app=APP Application being installed. [default: firefox] 18:15:07 INFO - mkdir: /builds/slave/test/build/application 18:15:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:15:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:15:41 INFO - Reading from file tmpfile_stdout 18:15:41 INFO - Output received: 18:15:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:15:41 INFO - Running post-action listener: _resource_record_post_action 18:15:41 INFO - [mozharness: 2016-10-01 01:15:41.599375Z] Finished install step (success) 18:15:41 INFO - [mozharness: 2016-10-01 01:15:41.599490Z] Running run-tests step. 18:15:41 INFO - Running pre-action listener: _resource_record_pre_action 18:15:41 INFO - Running main action method: run_tests 18:15:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:15:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:15:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:15:41 INFO - 2016-09-30 18:15:41.614 screenresolution[2213:34203] starting screenresolution argv=screenresolution get 18:15:41 INFO - 2016-09-30 18:15:41.617 screenresolution[2213:34203] Display 0: 1600x1200x32@60 18:15:41 INFO - 2016-09-30 18:15:41.622 screenresolution[2214:34205] starting screenresolution argv=screenresolution list 18:15:41 INFO - Available Modes on Display 0 18:15:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:15:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:15:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:15:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:15:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:15:41 INFO - Intel Iris: 18:15:41 INFO - Chipset Model: Intel Iris 18:15:41 INFO - Type: GPU 18:15:41 INFO - Bus: Built-In 18:15:41 INFO - VRAM (Dynamic, Max): 1536 MB 18:15:41 INFO - Vendor: Intel (0x8086) 18:15:41 INFO - Device ID: 0x0a2e 18:15:41 INFO - Revision ID: 0x0009 18:15:41 INFO - Displays: 18:15:41 INFO - Display: 18:15:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:15:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:15:41 INFO - Display Serial Number: 1600x1200 60 18:15:41 INFO - Main Display: Yes 18:15:41 INFO - Mirror: Off 18:15:41 INFO - Online: Yes 18:15:41 INFO - Rotation: Supported 18:15:41 INFO - Return code: 0 18:15:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:15:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:15:41 INFO - Python 2.7.3 18:15:41 INFO - Return code: 0 18:15:41 INFO - grabbing minidump binary from tooltool 18:15: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')]} 18:15:41 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 0x107809d30>, '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 0x10762fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107801b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:15:41 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 18:15:41 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 18:15:41 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 18:15:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:15:41 INFO - Return code: 0 18:15:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:15:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:15:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:15:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:15:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:15:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0227', '--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 18:15:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0227 --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 18:15:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fy7HxJy5Pd/Render', 18:15:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:15:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:15:41 INFO - 'HOME': '/Users/cltbld', 18:15:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:15:41 INFO - 'LOGNAME': 'cltbld', 18:15:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:15:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:15:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:41 INFO - 'MOZ_NO_REMOTE': '1', 18:15:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:15:41 INFO - 'NO_EM_RESTART': '1', 18:15:41 INFO - 'PAGER': '/bin/cat', 18:15:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:15:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:41 INFO - 'PWD': '/builds/slave/test', 18:15:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:15:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:15:41 INFO - 'SHELL': '/bin/bash', 18:15:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYLizVV8IC/Listeners', 18:15:41 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 18:15:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:15:41 INFO - 'USER': 'cltbld', 18:15:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:15:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:15:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:41 INFO - 'XPC_FLAGS': '0x0', 18:15:41 INFO - 'XPC_SERVICE_NAME': '0', 18:15:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:15:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0227', '--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 18:15:42 INFO - mozversion application_buildid: 20160930152455 18:15:42 INFO - mozversion application_changeset: ac89a649cbb96c6e50446b0df901e9d7d8963f24 18:15:42 INFO - mozversion application_display_name: Nightly 18:15:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:15:42 INFO - mozversion application_name: Firefox 18:15:42 INFO - mozversion application_remotingname: firefox 18:15:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:15:42 INFO - mozversion application_vendor: Mozilla 18:15:42 INFO - mozversion application_version: 52.0a1 18:15:42 INFO - mozversion platform_buildid: 20160930152455 18:15:42 INFO - mozversion platform_changeset: ac89a649cbb96c6e50446b0df901e9d7d8963f24 18:15:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:15:42 INFO - mozversion platform_version: 52.0a1 18:15:42 INFO - using testdate: 1475284542 18:15:42 INFO - actual date: 1475284542 18:15:42 INFO - starting webserver on 'localhost:62370' 18:15:42 INFO - SUITE-START | Running 2 tests 18:15:42 INFO - TEST-START | dromaeo_css 18:15:42 INFO - operating with platform_type : mac_ 18:15:42 INFO - Initialising browser for dromaeo_css test... 18:15:42 INFO - TEST-INFO | started process 2224 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/tmpwwsgHF/profile http://localhost:62370/getInfo.html) 18:15:45 INFO - PROCESS | 2224 | 2016-09-30 18:15:45.066 plugin-container[2226:34476] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e37, name = 'com.apple.tsm.portname' 18:15:45 INFO - PROCESS | 2224 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:15:45 INFO - PROCESS | 2224 | 2016-09-30 18:15:45.069 plugin-container[2226:34476] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 18:15:45 INFO - PROCESS | 2224 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:15:45 INFO - PROCESS | 2224 | 2016-09-30 18:15:45.069 plugin-container[2226:34476] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:15:45 INFO - PROCESS | 2224 | __metrics Screen width/height:1600/1200 18:15:45 INFO - PROCESS | 2224 | colorDepth:24 18:15:45 INFO - PROCESS | 2224 | Browser inner width/height: 1024/689 18:15:45 INFO - PROCESS | 2224 | __metrics 18:15:45 ERROR - PROCESS | 2224 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 18:15:45 INFO - TEST-INFO | 2224: exit 0 18:15:45 INFO - Browser initialized. 18:15:45 INFO - Running cycle 1/1 for dromaeo_css test... 18:15:45 INFO - TEST-INFO | started process 2227 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/tmpwwsgHF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:15:48 INFO - PROCESS | 2227 | 2016-09-30 18:15:48.392 plugin-container[2228:34592] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491f, name = 'com.apple.tsm.portname' 18:15:48 INFO - PROCESS | 2227 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:15:48 INFO - PROCESS | 2227 | 2016-09-30 18:15:48.395 plugin-container[2228:34592] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9403, name = 'com.apple.CFPasteboardClient' 18:15:48 INFO - PROCESS | 2227 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:15:48 INFO - PROCESS | 2227 | 2016-09-30 18:15:48.395 plugin-container[2228:34592] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:15:57 INFO - PROCESS | 2227 | RSS: Main: 239751168 18:15:57 INFO - PROCESS | 2227 | 18:16:47 INFO - PROCESS | 2227 | 1475284607575 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] 18:18:59 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:62370/tests/dromaeo/cssquery-dojo.html (next: http://localhost:62370/tests/dromaeo/cssquery-ext.html) 18:19:00 INFO - PROCESS | 2227 | RSS: Main: 255602688 18:19:00 INFO - PROCESS | 2227 | 18:22:03 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:62370/tests/dromaeo/cssquery-ext.html (next: http://localhost:62370/tests/dromaeo/cssquery-jquery.html) 18:22:03 INFO - PROCESS | 2227 | RSS: Main: 265719808 18:22:03 INFO - PROCESS | 2227 | 18:25:05 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:62370/tests/dromaeo/cssquery-jquery.html (next: http://localhost:62370/tests/dromaeo/cssquery-mootools.html) 18:25:06 INFO - PROCESS | 2227 | RSS: Main: 271478784 18:25:06 INFO - PROCESS | 2227 | 18:28:07 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:62370/tests/dromaeo/cssquery-mootools.html (next: http://localhost:62370/tests/dromaeo/cssquery-prototype.html) 18:28:08 INFO - PROCESS | 2227 | RSS: Main: 271085568 18:28:08 INFO - PROCESS | 2227 | 18:31:11 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:62370/tests/dromaeo/cssquery-prototype.html (next: http://localhost:62370/tests/dromaeo/cssquery-yui.html) 18:31:11 INFO - PROCESS | 2227 | RSS: Main: 276496384 18:31:11 INFO - PROCESS | 2227 | 18:34:14 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:62370/tests/dromaeo/cssquery-yui.html (next: http://localhost:62370/tests/dromaeo/cssquery-dojo.html) 18:34:15 INFO - PROCESS | 2227 | RSS: Main: 279220224 18:34:15 INFO - PROCESS | 2227 | 18:34:15 INFO - PROCESS | 2227 | __start_tp_report 18:34:15 INFO - PROCESS | 2227 | _x_x_mozilla_page_load 18:34:15 INFO - PROCESS | 2227 | _x_x_mozilla_page_load_details 18:34:15 INFO - PROCESS | 2227 | |i|pagename|runs| 18:34:15 INFO - PROCESS | 2227 | |0;dojo.html;3217.903462896113;3322.4019676458256;3333.266681330107;3340.2317466982595;3358.5465962095127;4082.7958602069925;5705.429457054298;5725.885482290357;5729.885402291951;5755.827325180241;5573.185541301647;5698.031334673105;5760.135979603051;5782.3639399666;5805.448482394177;5148.691078535281;5234.528892399683;5252.317198764155;5261.447548007462;5276.736163191844;5225.163973764198;5285.4450282720145;5302.867428314285;5308.814191503298;5389.021217523297;4406.669499787513;5151.330327057496;5260.736963151865;5296.311479507678;5338.733063346836;5177.301064356339;5221.634485586011;5251;5259.342582177228;5294.814681486129;5192.454792246797;5197.922031169498;5211.42674305826;5244.79020839166;5270.288511051036;4418.403515525395;5655.773769049274;5682.062459694145;5688.175214593902;5787.189793428924;6118.893302669174;6134.723937422782;6163.476104531121;6236.064590311445;6246.0630905364105;6225.190725205694;6226.470749986257;6237.812865614039;6242.563020588561;6259.405356491172;4728.290756386536;5725.627834190765;5831.620944638585;5845.240118784531;5846.269216347957;6193.1329613854095;6199.535034872402;6233;6278;6326;6156.569040167145;6172.444479996821;6182.072689096672;6189.6286222826775;6193.349698281616;6144.293406258248;6181;6245.250569931546;6260.46786023184;6263.812085637438;6116.847078823065;6119.938800612026;6142.938570614237;6146.832101900624;6191.659458729723;6185.659788711664;6221.875562488815;6224.62652240867;6227.501799856001;6242.344553821874;4928.063667903087;4930.235813448921;4940.036692844933;4942.357493525818;4961.801527938915;4350.586694264103;4357.237483440449;4412.801423935931;4474.373587697725;4484.0583477469445;5868.530517558671;5881.500072493886;5927;5977.820665380045;5998.910016349758;6241.500679945593;6247.437730604177;6264;6269.373062693785;6275.843103922439;5803.216565763568;5877;5944;5975.43233392827;5981.37195594465;5391.05656510117;5397.568194544427;5440.401555828856;5445;5494.478024587658;6345.206849143857;6366.872662546723;6384.6169229846355;6397.616143031433;6409.743610255631;6160.13758073861;6181.907271390843;6243.640965828614;6254.967930291451;6271.153394291712;6240.438360547572;6245.219347581552;6298.1812364392335;6301.4328710416285;6348.746050158126;5094.337736094432;6192.659403732838;6203.2245969253845;6215.65813880241;6224.1597384352535;5138.768755405941;5650.85872853182;6084.6349219047;6242.843928901813;6255.968720156552;5012.523810238022;5932.317783454958;6055.455009049207;6080.939190608037;6121.0206366981065;5535.7785688572;5737.770489180553;5999.790007349722;6186.845328866814;6191.81424557264;4608.400907882088;5920.230370051866;5987.31145918225;6077.4226448487325;6091.573589848846;5154.278401023785;6089.421504957022;6107.85643655375;6118.724657390517;6194.0708893666315;6023.668698221464;6028.87942241144;6036.456718895321;6038.728257228523;6050.273967123973 18:34:15 INFO - PROCESS | 2227 | |1;ext.html;472.76125556593917;507.37592760904084;523.3432042786304;528.896865111303;532.7241257189031;39772.60227397725;41218;41244.38133428001;43094.569054309526;43112.56887431125;41822.58177418225;42375.57624423755;42457.150856982844;43217;43242.783786081105;9823.508824558798;9987.650432234874;10016.148627366683;10017.348872323311;10056;9796.363236389612;9902.207823374149;9908.504574771232;9919;9920.156786673142;9451.95274023629;9889.10998010179;9932.35439696421;9932.453715045669;9952.054554817281;9867.309288349818;9867.753306167331;9889.505524723734;9889.90110098897;9892.653757118538;9748.95125524375;9829.80340393188;9832.508374581314;9843.261755368376;9848.60605575776;39130;39838.203235935405;40166.59833401658;40596;40721.981950451176;3236.4659831127838;3256.4138455078078;3324.401607710611;3340.532325474411;3725.2735716535285;29003.41993160146;30894.22764430885;30921.226969325722;30953.499765049368;31037.534436983464;3192.138122706856;3330.833458327074;3363;3439.449688049925;3443.966809957028;486053;488508;489830;491516;492652;60420.69789651024;65090.67454662791;66107.00839487411;66192;66371.33628663652;64909.350906490334;64925.67537162284;65525;69408.65295673489;69571.08645765166;16252.67494650124;16488.422905198262;16501.669966600602;16502.092384918706;16516.339346426248;12542;12693.746125077447;12705.364731763375;12713.87286127127;12848.614541563767;16699;19693.803061969484;20385.796142038686;20422.69365959512;20496.487587810127;8279.751607451786;8300.750977470685;8469.872951905727;8477.533735644598;8492.787680308042;859.7291853066264;941.2517048946104;951.8714973478492;1024.574801457387;1074.4735079810837;29551.965681201054;29711;30180;30305.69694303073;30401.743696438127;907.7140700679397;1041.313072822033;1068.792264740838;1085.473545330514;1090.825467925144;13810.895128389704;15028.647421732097;15564.299606517734;15667.764983525256;15888.2055897207;571708;650931;666106;668992;672168;51833.7408312956;55825;55937.16094258589;55949.720251399296;56052.71973640106;1096.9561217551368;1340.7921772125337;1377.4903285784123;1378.5588611644177;1396.3506969259342;28402.289942751595;28525.857370713013;29564.852175738983;29996.700033000263;30097.69902300949;36707.81646091752;45773.54226457693;48632.5136748628;48672.26991595058;48713.53859384224;571978;572256;573532;574907;578430;34179;40688.3896741555;42153.78923105365;42488;42635;45787.77106114582;46422.30366544437;46935.53064469311;47426.52573474221;47483.575492735275;37215.62784372122;37464.812675936446;38402.2319553613;38547;38565.843024709306;34023.48964765481;35452.64547354494;38453;38895.611043890334;39822.2035559293;32254.19364515906;32869.67130328666;37249.441258380604;37480;38002.429963550014;32218.83890580532;33651;34935.65064349324;35039.47440788441;35087;25522.979080836558;25760.355991099623;25790.871045644653;26084.34789130287;26393.472130557664 18:34:15 INFO - PROCESS | 2227 | |2;jquery.html;369.52700543304564;372.0290042987802;378.92232092421045;381.94652748615215;400.6234139908486;16981.83018169818;18729.438116856498;20334.08496617652;20528.692069618966;20723.378298651027;20520.89739551304;20541.79458205421;20715.06782194801;20798.16807327705;20975.119602873478;5298.6555873868265;5484.533814625742;5855.121731740241;6003.0395136778125;6006.099085137221;5108.361454818148;5954.851128721795;6081.6351018939;6085.939140608603;6100.176476175704;5715.571332150084;5881.4118588141055;5911;5939.376365481627;6058.454739073482;5705.972924873521;5887.735051922674;5929.792457264019;5947.6134051286745;5972.6715030673295;5119.308893299394;5152.458991805844;5162.6386152968935;5173.534381905609;5181.326427564431;24928.12751553705;25022.624660630107;25133.62299565508;25317.24048278536;25452.87273563639;2359.0210062823908;2373.905043798246;3010.9397812043635;3074.1546074829375;3084.6452657944174;14350.210738409383;15319.617009574738;15444.5366639001;15469.845301546953;15712.842871571253;1573.5200763767045;1849.3804575467234;1870.635226130905;1900.515368581017;1912.2733361322607;116375;116619;116882;117139;119651;9258.629654813732;10286.428392901775;10487.580496780196;10689.35863848156;10954.616588419367;12965.805512917314;13037.739245215043;13045.739085218242;13051.804222936664;13068.803967940488;8052.677892884336;8280.213379729037;8285.25432711059;8428.241458268785;8444.957775211085;8370.53962032094;8390.706325278587;8397.916020839713;8580.056193818675;8622.741317760478;4965.851024469272;5367.570594352443;5678.346990096192;6179.721912513947;6406.199225096863;1643.8191798902112;1819.6178802451634;1859.4235786906102;1862.5529872830423;1867.486441228673;733.9339459448676;993.5429702336861;999.9600015999424;1012.3622118065572;1044.5769463367349;20695.8965205173;21175;21177.788222117582;21402.78597214008;21827.454313642287;979.3438396274513;980.3823591137538;992.8212921674168;1007.5012868630075;1021.8262895307815;10969.23215374916;11641.068714502819;11642;11674.299542027336;11741.823872641917;551957.2402137964;577962;581914;586837;596366;117916;121655;123102.76897230913;123335;124548;1001.9248556358302;1023.5752162852482;1030.4487099401633;1047.8742550893942;1048.4862417415563;18542.536436589195;19327;19336.323228686928;19393.8060619392;19396.515087122934;46737.29894051524;47198.05603887834;48191.5180848187;51817.74091129671;52190.73904630452;66134.66932665306;67294.66352668402;67453.66273168799;67570.6350277738;68000.6599966997;7132.179799322937;7442.553446793209;7681.233316337385;7752.418568607264;7818.218178182136;6962.930370696228;9860.506974651094;10057.798844023226;10403.271770975958;11843.703907402383;9135.680251191176;11028.22802403824;11213.495392707511;11269;11354.375509347065;10434.530446130095;10601;10811.405372704263;10821.458927053458;11261.380624065427;9994.750131246778;10507.318828987325;10652.57389704403;11393.487293071666;11719.941400293284;7744.651490683045;7869.780184071478;7926.841463170589;7945.48354356965;7978;10759.838602420814;11088.390138542458;11319.660410187707;11432.199746017699;11634.301941883514 18:34:15 INFO - PROCESS | 2227 | |3;mootools.html;1534.6700459401227;1576.0307410942269;1786.9821301786985;1871.3824437935482;1892.063428602841;8067.636956336964;8340.74977750667;8590.48457092575;9134.36059475837;9172.266218702505;8521.531315777629;8850.867236991435;8882.911170888288;8951.865722014176;8953.194212520872;1940.9417717468464;2366.526694661066;2401.135591187171;2401.4716762312264;2410.240774156144;2002.5294055896852;2333.4866329407505;2376.869272190029;2393.198278576679;2411.360989337818;2362.444825466023;2387.9641805372935;2391.234804862445;2391.3184742348435;2407.626817843237;2373.821963352747;2375.489269807;2379.583572874749;2393.652920326552;2403.4231784371623;2347.882605869717;2351.7883390494767;2355.222776483756;2359.775821296974;2361.669366288721;8910.955445222733;8988.685396011173;9013.459192448474;9035.774105647346;9038.81922361549;5249.396319423277;5279.366476022902;5285.07511185541;5332.786688532454;5370.624056316059;3497.6502349765074;3620.9637903621156;3642.1258897864323;3693.6675699187203;3706.499622550948;1064.893510648929;1099.8460215569746;1108.9001989820874;1124.5558004588142;1125.58915995661;11721.238119522204;11732.706682332924;11829.763404731943;11858.881411185865;11875;4334.523202447728;4504.3919070925;4766.97510035344;4792.113459010095;4793.424789025269;8574.914250857537;9417.90582094184;10033.548490317953;10101.646442374482;10134.54394552247;5323.627346085815;5327.094393953037;5333.653312534673;5342.1185504392115;5342.626016178908;4397.274449715825;4564.360989461478;4759.190937540627;4881.511848815161;4888.7311197884455;3892.844286228519;3961.6926414283216;4034.23349563582;4041.2927737645796;4100.261952848516;1281.8910392616544;1284.800855867342;1288.6005338345144;1296.0215037646517;1302.5115581656878;870.9303255739652;887.0951629338039;890.4835195586544;905.3345790843724;914.3919293669768;5691.857703557444;5727.3699893011735;5868.501177399969;5919.93441180583;6006.069059295816;424.24907912994445;427.79465856389106;430.59954242554727;440.04949309491343;441.77690266415254;3323.2026306611433;3568.50040994261;3722.218334149859;3747.2318174774105;3885.834249725071;10559.155267578575;10843.295185812896;10921.726956826144;10931.726706832234;10993.955574220436;10879.945600272107;10901.182411319183;10903.400312982862;10921.672349829518;10926.890731092588;8237.093919668832;8335.708250211335;8343.874841877256;8356.164383561716;8363.707270245512;4669.39297891272;5545.1682247663175;5585.832425027255;5728.684922329312;5794.304683438015;9866.457344846101;10048.899511004796;10051.246156538142;10076.042775936274;10111.949440252753;9724.124828765445;10181.338213016426;10215.336003159771;10251.12865406419;10259.794804104025;7006.52862898797;7185.389241914496;7235.204127546071;7475.028246327943;7673.271039251262;8046.114927358103;8164.061132969785;8302.916970830214;8328.375371847014;8333.208345207195;7288.927110728825;7402.148752893486;7409.592472414069;7443.627818609156;7531.774046778605;7209.567425954459;7293.124825021031;7358.190599034209;7548.811279718051;7560.130584982798;6662.167229096363;6718.832029199114;6765.1205343305055;6768.627725475146;6781.966090169518;4937.382827146606;4962.950370496394;4966.478519755374;5007.098722229888;5015.523525265094;4867.050925069578;4896.069746748107;4982.7259500727805;5108.540231379194;5109.131447653834 18:34:15 INFO - PROCESS | 2227 | |4;prototype.html;291.95620656901474;302.14643631740336;309.82649716158954;312.3004469987229;315.5881574545219;5237;5486.890262194754;5720.742566584503;5748.109043098321;5826.592138550303;5577.8605534861645;5600;5688.60179787414;5712.628679135863;5720.768900128953;4036.677065834741;4293.36851996241;4308.870733877989;4332.06860524987;4332.28517294646;3363;4069.97965010176;4268.90504384322;4331.956680433203;4338.436003319564;3812.1803812180374;3909.43313219583;4174.624283814456;4249.936250956238;4257;3848.1918797052654;4243.27864263076;4257.936130958038;4258.040661740092;4265.274903266452;3035.468792961245;3304.7686661933676;3466.8039526363364;3556.6798988091195;3668.6514781095752;5300.655457395257;5495.917561236584;5556.805511807067;5613.550915926716;5643.661380317194;4866.318715379814;4887.75163424825;4895.192293271606;4901;4909.68087074339;4812.5668689818085;4939.036887806879;5894.882102357972;6031.125486804389;6058;3738.056210770384;3745.831437585315;3965.4448377227213;3979.343408337621;4057.9594204057876;5608.943910560883;6159.722812473449;6283.654399008053;6305.369463053704;6308.779192728279;3495.528103706018;4123.8556650517085;4130.731502452331;4142.979285103555;4144.41978123063;3498.9825050875093;3703.9814800925824;4207.705460617789;4222.5087198553;4239.618434340862;4384.4519435070615;4432.024954510002;4526.913540750196;4925.335079764258;4925.655204135676;5197.7401129943355;5463.836084917459;5795.536357091422;5990.31111422192;6044.697765111727;1321.6365499487526;1404.7963045358365;1420.7229590229806;1435.7846323051626;1438.5828109848237;756.3797685897512;821.2403526737744;840.1556435781999;878.7846977490428;889.3285569395065;3900.863469778544;4142.896427589274;4346;4427.02997166258;4760.309755085494;4452.799624016927;4622.583967442946;5224.7126408047925;5227.817026404057;5230.686158830483;4251.659867210616;4543.159515489645;4687.648426368036;4747.477777444478;4808.735519546389;2162.243214874781;2202.8898555072183;2511.9246422607353;2612.6603541539857;2690.0719251858077;4591.494935557086;4605.858275901722;4775.158780525581;5383.488568585978;5401.297831281908;4558.156741002925;4602.12367270456;4611.463694967754;4718.952810471851;5309.495597918191;4503.076869241798;4777.4267087949665;5182.222666600041;5208.270842082112;5402.082875247323;4603.700759450625;4649.604783593432;4833.830815921426;4839.128956787671;5219.817306394258;4384.539623339631;4457.665675074318;4655.464621568428;4876.536729010738;5116.565091967075;4835.8065677372515;5356.785728570813;6070.180525628984;6118.112873633209;6290.364505228582;3497.650234976482;3943.250782351459;3947.6052394760295;4131.442255295497;4171.683511608339;5712.6286791358425;5715.770849368666;6163.935081747665;6178.320384757583;6210.130581718653;5337.9466205339;5741.45456181662;5942.286925568959;5957.3744756799915;5965.001724422367;5166.199239117943;5637.126245431965;5691.8861622768145;5958.970205148947;5978.790742323998;4622.798072501107;5187.302602646527;5317.787288508571;5575.289192737393;5851.151583020353;5942.58401911862;5959.0763431668165;5959.940400596112;5965.701714914324;6010.046734811292;5327.680339179662;5664.830055098354;5861.912071319019;5867.295924488918;5919.378465261086 18:34:15 INFO - PROCESS | 2227 | |5;yui.html;631.7113089297833;660.7291010685619;747.7980945144815;787.5943888897216;795.0737390939554;13499.797503037462;15154.545363639094;15720.213989300546;15946.883718139736;16326.183690815456;14260.215688137148;14376.640583985405;14396.064255823365;14406.495772647959;14406.783898241509;678.8268991407184;707.7275249029109;834.2575108153748;835.5446281776447;866.2809867809734;722.3679280629449;863.7624653220383;869.699953516036;880.0759202837028;887.9334049946248;939.9248060155205;942.2226663003015;944.1549812917469;944.2917811641269;946.049220533363;901.6483571407124;931.4876817750244;933.2953620016843;936.1247233836317;940.3041749105647;645.7707513832569;671.92944740802;692.4287462843124;697.9162500499973;698.4901022253783;11887.989520890735;12856.292903890282;14769.187694676788;14909.179995100265;14920.104793712304;11954.641360759191;12633.368331583475;14507.492237771627;14512.637184070378;14711.264436778225;10715.35707857531;11791.23356981802;11874.881251187464;11878.940605297004;12127.696807579792;3785.9432108518395;3802.429635554661;3827.311084004878;3858.4019476980834;3962.366021436585;112676.36618169058;113989;117488;118581.40709296484;119655;9116.41934130895;11046.447677616086;11108.333499989865;11269.09847212221;11483.957551507287;23211.419714507272;23606.52786944286;23627.7637223629;23666.053357865494;23671.7632823673;7999.120096789348;8214.424990250745;8219.342452603778;8222.876656850152;8307.792305192419;4280.76455794928;4303.429248324339;4410.757408342572;4528.622049208937;4647.953520464752;3446.672566106216;3538.3454060998797;3562.4834399011997;3575.588807287143;3714.888553343404;705.0587465733201;705.2735682247294;712.1952194020827;725.3073314984283;726.208432808231;605.8243109498196;657.4411750012547;658.9341065893373;680.7651360280695;686.3994005245411;8977.865332020025;9512.619495220251;9618.857617623748;9666.75833104164;9762.75593110178;612.766466230587;651.5046774057149;668.7458765669112;674.9021989070192;698.0506511144839;4352.948223877339;4469.977650111794;4672.509386514435;4688.132695451352;4821.879453013703;142893.28553357167;161160;169564;169869;170264.4460333096;134378.32810835884;137282;138592;140862;141211.29394352963;72535.99999999894;84260;105049.47475262727;106653.46673266584;109056;7277.235890231449;8299.08710041907;8959.955200223958;9075.54622268897;9090.727278181665;52694.73652631712;59628.40371596228;59947.92861631762;60710.69644651748;61254.79334256638;68862;94196;95998;96491.035089651;97972.51013744886;11692.70768230801;12434.813477798021;12707.428165732379;12955.481780729042;13122.081454298108;14574.927125364306;14774.261286935825;14846.331915064064;14928;15288.235588220323;13558.93220533891;13655.658608534866;13713.520026799413;13782.93108534451;14056.297185140907;10338.379697218192;13796.034277600873;14113.647158821112;14302.57092287233;14309.356078976263;13344.799828002782;13454.93272533631;13478;13663.453461861813;13752.381142848395;11037.227394082658;11124.555017799197;11377.317360958368;11442.542298307975;11722.120840937134;10030.197584193102;10920.78158436811;10947.397893115636;11416.143789215675;11576.768464630828 18:34:15 INFO - PROCESS | 2227 | __end_tp_report 18:34:15 INFO - PROCESS | 2227 | __start_cc_report 18:34:15 INFO - PROCESS | 2227 | _x_x_mozilla_cycle_collect,357 18:34:15 INFO - PROCESS | 2227 | __end_cc_report 18:34:15 INFO - PROCESS | 2227 | __startTimestamp1475285655204__endTimestamp 18:34:15 INFO - PROCESS | 2227 | 18:34:15 INFO - PROCESS | 2227 | ------- Summary: start ------- 18:34:15 INFO - PROCESS | 2227 | Number of tests: 6 18:34:15 INFO - PROCESS | 2227 | 18:34:15 INFO - PROCESS | 2227 | [#0] dojo.html Cycles:165 Average:5700.65 Median:5977.82 stddev:678.97 (11.4%) stddev-sans-first:652.52 18:34:15 INFO - PROCESS | 2227 | Values: 3217.9 3322.4 3333.3 3340.2 3358.5 4082.8 5705.4 5725.9 5729.9 5755.8 5573.2 5698.0 5760.1 5782.4 5805.4 5148.7 5234.5 5252.3 5261.4 5276.7 5225.2 5285.4 5302.9 5308.8 5389.0 4406.7 5151.3 5260.7 5296.3 5338.7 5177.3 5221.6 5251.0 5259.3 5294.8 5192.5 5197.9 5211.4 5244.8 5270.3 4418.4 5655.8 5682.1 5688.2 5787.2 6118.9 6134.7 6163.5 6236.1 6246.1 6225.2 6226.5 6237.8 6242.6 6259.4 4728.3 5725.6 5831.6 5845.2 5846.3 6193.1 6199.5 6233.0 6278.0 6326.0 6156.6 6172.4 6182.1 6189.6 6193.3 6144.3 6181.0 6245.3 6260.5 6263.8 6116.8 6119.9 6142.9 6146.8 6191.7 6185.7 6221.9 6224.6 6227.5 6242.3 4928.1 4930.2 4940.0 4942.4 4961.8 4350.6 4357.2 4412.8 4474.4 4484.1 5868.5 5881.5 5927.0 5977.8 5998.9 6241.5 6247.4 6264.0 6269.4 6275.8 5803.2 5877.0 5944.0 5975.4 5981.4 5391.1 5397.6 5440.4 5445.0 5494.5 6345.2 6366.9 6384.6 6397.6 6409.7 6160.1 6181.9 6243.6 6255.0 6271.2 6240.4 6245.2 6298.2 6301.4 6348.7 5094.3 6192.7 6203.2 6215.7 6224.2 5138.8 5650.9 6084.6 6242.8 6256.0 5012.5 5932.3 6055.5 6080.9 6121.0 5535.8 5737.8 5999.8 6186.8 6191.8 4608.4 5920.2 5987.3 6077.4 6091.6 5154.3 6089.4 6107.9 6118.7 6194.1 6023.7 6028.9 6036.5 6038.7 6050.3 18:34:15 INFO - PROCESS | 2227 | 18:34:15 INFO - PROCESS | 2227 | [#1] ext.html Cycles:180 Average:70757.99 Median:40642.19 stddev:153284.77 (377.2%) stddev-sans-first:153623.93 18:34:15 INFO - PROCESS | 2227 | Values: 472.8 507.4 523.3 528.9 532.7 39772.6 41218.0 41244.4 43094.6 43112.6 41822.6 42375.6 42457.2 43217.0 43242.8 9823.5 9987.7 10016.1 10017.3 10056.0 9796.4 9902.2 9908.5 9919.0 9920.2 9452.0 9889.1 9932.4 9932.5 9952.1 9867.3 9867.8 9889.5 9889.9 9892.7 9749.0 9829.8 9832.5 9843.3 9848.6 39130.0 39838.2 40166.6 40596.0 40722.0 3236.5 3256.4 3324.4 3340.5 3725.3 29003.4 30894.2 30921.2 30953.5 31037.5 3192.1 3330.8 3363.0 3439.4 3444.0 486053.0 488508.0 489830.0 491516.0 492652.0 60420.7 65090.7 66107.0 66192.0 66371.3 64909.4 64925.7 65525.0 69408.7 69571.1 16252.7 16488.4 16501.7 16502.1 16516.3 12542.0 12693.7 12705.4 12713.9 12848.6 16699.0 19693.8 20385.8 20422.7 20496.5 8279.8 8300.8 8469.9 8477.5 8492.8 859.7 941.3 951.9 1024.6 1074.5 29552.0 29711.0 30180.0 30305.7 30401.7 907.7 1041.3 1068.8 1085.5 1090.8 13810.9 15028.6 15564.3 15667.8 15888.2 571708.0 650931.0 666106.0 668992.0 672168.0 51833.7 55825.0 55937.2 55949.7 56052.7 1097.0 1340.8 1377.5 1378.6 1396.4 28402.3 28525.9 29564.9 29996.7 30097.7 36707.8 45773.5 48632.5 48672.3 48713.5 571978.0 572256.0 573532.0 574907.0 578430.0 34179.0 40688.4 42153.8 42488.0 42635.0 45787.8 46422.3 46935.5 47426.5 47483.6 37215.6 37464.8 38402.2 38547.0 38565.8 34023.5 35452.6 38453.0 38895.6 39822.2 32254.2 32869.7 37249.4 37480.0 38002.4 32218.8 33651.0 34935.7 35039.5 35087.0 25523.0 25760.4 25790.9 26084.3 26393.5 18:34:15 INFO - PROCESS | 2227 | 18:34:15 INFO - PROCESS | 2227 | [#2] jquery.html Cycles:180 Average:33908.57 Median:13651.02 stddev:96543.46 (707.2%) stddev-sans-first:96781.45 18:34:15 INFO - PROCESS | 2227 | Values: 369.5 372.0 378.9 381.9 400.6 16981.8 18729.4 20334.1 20528.7 20723.4 20520.9 20541.8 20715.1 20798.2 20975.1 5298.7 5484.5 5855.1 6003.0 6006.1 5108.4 5954.9 6081.6 6085.9 6100.2 5715.6 5881.4 5911.0 5939.4 6058.5 5706.0 5887.7 5929.8 5947.6 5972.7 5119.3 5152.5 5162.6 5173.5 5181.3 24928.1 25022.6 25133.6 25317.2 25452.9 2359.0 2373.9 3010.9 3074.2 3084.6 14350.2 15319.6 15444.5 15469.8 15712.8 1573.5 1849.4 1870.6 1900.5 1912.3 116375.0 116619.0 116882.0 117139.0 119651.0 9258.6 10286.4 10487.6 10689.4 10954.6 12965.8 13037.7 13045.7 13051.8 13068.8 8052.7 8280.2 8285.3 8428.2 8445.0 8370.5 8390.7 8397.9 8580.1 8622.7 4965.9 5367.6 5678.3 6179.7 6406.2 1643.8 1819.6 1859.4 1862.6 1867.5 733.9 993.5 1000.0 1012.4 1044.6 20695.9 21175.0 21177.8 21402.8 21827.5 979.3 980.4 992.8 1007.5 1021.8 10969.2 11641.1 11642.0 11674.3 11741.8 551957.2 577962.0 581914.0 586837.0 596366.0 117916.0 121655.0 123102.8 123335.0 124548.0 1001.9 1023.6 1030.4 1047.9 1048.5 18542.5 19327.0 19336.3 19393.8 19396.5 46737.3 47198.1 48191.5 51817.7 52190.7 66134.7 67294.7 67453.7 67570.6 68000.7 7132.2 7442.6 7681.2 7752.4 7818.2 6962.9 9860.5 10057.8 10403.3 11843.7 9135.7 11028.2 11213.5 11269.0 11354.4 10434.5 10601.0 10811.4 10821.5 11261.4 9994.8 10507.3 10652.6 11393.5 11719.9 7744.7 7869.8 7926.8 7945.5 7978.0 10759.8 11088.4 11319.7 11432.2 11634.3 18:34:15 INFO - PROCESS | 2227 | 18:34:15 INFO - PROCESS | 2227 | [#3] mootools.html Cycles:180 Average:5694.90 Median:4714.29 stddev:3242.97 (68.8%) stddev-sans-first:3237.00 18:34:15 INFO - PROCESS | 2227 | Values: 1534.7 1576.0 1787.0 1871.4 1892.1 8067.6 8340.7 8590.5 9134.4 9172.3 8521.5 8850.9 8882.9 8951.9 8953.2 1940.9 2366.5 2401.1 2401.5 2410.2 2002.5 2333.5 2376.9 2393.2 2411.4 2362.4 2388.0 2391.2 2391.3 2407.6 2373.8 2375.5 2379.6 2393.7 2403.4 2347.9 2351.8 2355.2 2359.8 2361.7 8911.0 8988.7 9013.5 9035.8 9038.8 5249.4 5279.4 5285.1 5332.8 5370.6 3497.7 3621.0 3642.1 3693.7 3706.5 1064.9 1099.8 1108.9 1124.6 1125.6 11721.2 11732.7 11829.8 11858.9 11875.0 4334.5 4504.4 4767.0 4792.1 4793.4 8574.9 9417.9 10033.5 10101.6 10134.5 5323.6 5327.1 5333.7 5342.1 5342.6 4397.3 4564.4 4759.2 4881.5 4888.7 3892.8 3961.7 4034.2 4041.3 4100.3 1281.9 1284.8 1288.6 1296.0 1302.5 870.9 887.1 890.5 905.3 914.4 5691.9 5727.4 5868.5 5919.9 6006.1 424.2 427.8 430.6 440.0 441.8 3323.2 3568.5 3722.2 3747.2 3885.8 10559.2 10843.3 10921.7 10931.7 10994.0 10879.9 10901.2 10903.4 10921.7 10926.9 8237.1 8335.7 8343.9 8356.2 8363.7 4669.4 5545.2 5585.8 5728.7 5794.3 9866.5 10048.9 10051.2 10076.0 10111.9 9724.1 10181.3 10215.3 10251.1 10259.8 7006.5 7185.4 7235.2 7475.0 7673.3 8046.1 8164.1 8302.9 8328.4 8333.2 7288.9 7402.1 7409.6 7443.6 7531.8 7209.6 7293.1 7358.2 7548.8 7560.1 6662.2 6718.8 6765.1 6768.6 6782.0 4937.4 4963.0 4966.5 5007.1 5015.5 4867.1 4896.1 4982.7 5108.5 5109.1 18:34:15 INFO - PROCESS | 2227 | 18:34:15 INFO - PROCESS | 2227 | [#4] prototype.html Cycles:180 Average:4523.19 Median:4823.20 stddev:1411.77 (29.3%) stddev-sans-first:1379.54 18:34:15 INFO - PROCESS | 2227 | Values: 292.0 302.1 309.8 312.3 315.6 5237.0 5486.9 5720.7 5748.1 5826.6 5577.9 5600.0 5688.6 5712.6 5720.8 4036.7 4293.4 4308.9 4332.1 4332.3 3363.0 4070.0 4268.9 4332.0 4338.4 3812.2 3909.4 4174.6 4249.9 4257.0 3848.2 4243.3 4257.9 4258.0 4265.3 3035.5 3304.8 3466.8 3556.7 3668.7 5300.7 5495.9 5556.8 5613.6 5643.7 4866.3 4887.8 4895.2 4901.0 4909.7 4812.6 4939.0 5894.9 6031.1 6058.0 3738.1 3745.8 3965.4 3979.3 4058.0 5608.9 6159.7 6283.7 6305.4 6308.8 3495.5 4123.9 4130.7 4143.0 4144.4 3499.0 3704.0 4207.7 4222.5 4239.6 4384.5 4432.0 4526.9 4925.3 4925.7 5197.7 5463.8 5795.5 5990.3 6044.7 1321.6 1404.8 1420.7 1435.8 1438.6 756.4 821.2 840.2 878.8 889.3 3900.9 4142.9 4346.0 4427.0 4760.3 4452.8 4622.6 5224.7 5227.8 5230.7 4251.7 4543.2 4687.6 4747.5 4808.7 2162.2 2202.9 2511.9 2612.7 2690.1 4591.5 4605.9 4775.2 5383.5 5401.3 4558.2 4602.1 4611.5 4719.0 5309.5 4503.1 4777.4 5182.2 5208.3 5402.1 4603.7 4649.6 4833.8 4839.1 5219.8 4384.5 4457.7 4655.5 4876.5 5116.6 4835.8 5356.8 6070.2 6118.1 6290.4 3497.7 3943.3 3947.6 4131.4 4171.7 5712.6 5715.8 6163.9 6178.3 6210.1 5337.9 5741.5 5942.3 5957.4 5965.0 5166.2 5637.1 5691.9 5959.0 5978.8 4622.8 5187.3 5317.8 5575.3 5851.2 5942.6 5959.1 5959.9 5965.7 6010.0 5327.7 5664.8 5861.9 5867.3 5919.4 18:34:15 INFO - PROCESS | 2227 | 18:34:15 INFO - PROCESS | 2227 | [#5] yui.html Cycles:180 Average:25169.61 Median:3645.24 stddev:41217.45 (1130.7%) stddev-sans-first:41291.90 18:34:15 INFO - PROCESS | 2227 | Values: 631.7 660.7 747.8 787.6 795.1 13499.8 15154.5 15720.2 15946.9 16326.2 14260.2 14376.6 14396.1 14406.5 14406.8 678.8 707.7 834.3 835.5 866.3 722.4 863.8 869.7 880.1 887.9 939.9 942.2 944.2 944.3 946.0 901.6 931.5 933.3 936.1 940.3 645.8 671.9 692.4 697.9 698.5 11888.0 12856.3 14769.2 14909.2 14920.1 11954.6 12633.4 14507.5 14512.6 14711.3 10715.4 11791.2 11874.9 11878.9 12127.7 3785.9 3802.4 3827.3 3858.4 3962.4 112676.4 113989.0 117488.0 118581.4 119655.0 9116.4 11046.4 11108.3 11269.1 11484.0 23211.4 23606.5 23627.8 23666.1 23671.8 7999.1 8214.4 8219.3 8222.9 8307.8 4280.8 4303.4 4410.8 4528.6 4648.0 3446.7 3538.3 3562.5 3575.6 3714.9 705.1 705.3 712.2 725.3 726.2 605.8 657.4 658.9 680.8 686.4 8977.9 9512.6 9618.9 9666.8 9762.8 612.8 651.5 668.7 674.9 698.1 4352.9 4470.0 4672.5 4688.1 4821.9 142893.3 161160.0 169564.0 169869.0 170264.4 134378.3 137282.0 138592.0 140862.0 141211.3 72536.0 84260.0 105049.5 106653.5 109056.0 7277.2 8299.1 8960.0 9075.5 9090.7 52694.7 59628.4 59947.9 60710.7 61254.8 68862.0 94196.0 95998.0 96491.0 97972.5 11692.7 12434.8 12707.4 12955.5 13122.1 14574.9 14774.3 14846.3 14928.0 15288.2 13558.9 13655.7 13713.5 13782.9 14056.3 10338.4 13796.0 14113.6 14302.6 14309.4 13344.8 13454.9 13478.0 13663.5 13752.4 11037.2 11124.6 11377.3 11442.5 11722.1 10030.2 10920.8 10947.4 11416.1 11576.8 18:34:15 INFO - PROCESS | 2227 | -------- Summary: end -------- 18:34:15 INFO - PROCESS | 2227 | 18:34:15 INFO - TEST-INFO | 2227: exit 0 18:34:15 INFO - TEST-OK | dromaeo_css | took 1113595ms 18:34:15 INFO - TEST-START | kraken 18:34:15 INFO - operating with platform_type : mac_ 18:34:15 INFO - Initialising browser for kraken test... 18:34:15 INFO - TEST-INFO | started process 2232 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/tmpqMIZmQ/profile http://localhost:62370/getInfo.html) 18:34:18 INFO - PROCESS | 2232 | 2016-09-30 18:34:18.482 plugin-container[2233:36582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4713, name = 'com.apple.tsm.portname' 18:34:18 INFO - PROCESS | 2232 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:34:18 INFO - PROCESS | 2232 | 2016-09-30 18:34:18.485 plugin-container[2233:36582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9203, name = 'com.apple.CFPasteboardClient' 18:34:18 INFO - PROCESS | 2232 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:34:18 INFO - PROCESS | 2232 | 2016-09-30 18:34:18.485 plugin-container[2233:36582] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:34:18 INFO - PROCESS | 2232 | __metrics Screen width/height:1600/1200 18:34:18 INFO - PROCESS | 2232 | colorDepth:24 18:34:18 INFO - PROCESS | 2232 | Browser inner width/height: 1024/689 18:34:18 INFO - PROCESS | 2232 | __metrics 18:34:18 ERROR - PROCESS | 2232 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 18:34:19 INFO - TEST-INFO | 2232: exit 0 18:34:19 INFO - Browser initialized. 18:34:19 INFO - Running cycle 1/1 for kraken test... 18:34:19 INFO - TEST-INFO | started process 2234 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/tmpqMIZmQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:34:21 INFO - PROCESS | 2234 | 2016-09-30 18:34:21.707 plugin-container[2235:36699] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x891b, name = 'com.apple.tsm.portname' 18:34:21 INFO - PROCESS | 2234 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:34:21 INFO - PROCESS | 2234 | 2016-09-30 18:34:21.710 plugin-container[2235:36699] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 18:34:21 INFO - PROCESS | 2234 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:34:21 INFO - PROCESS | 2234 | 2016-09-30 18:34:21.710 plugin-container[2235:36699] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:34:31 INFO - PROCESS | 2234 | RSS: Main: 239988736 18:34:31 INFO - PROCESS | 2234 | 18:34:34 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:62370/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:62370/tests/kraken/driver.html?testName=audio-beat-detection) 18:34:35 INFO - PROCESS | 2234 | RSS: Main: 253833216 18:34:35 INFO - PROCESS | 2234 | 18:34:38 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:62370/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:62370/tests/kraken/driver.html?testName=audio-dft) 18:34:39 INFO - PROCESS | 2234 | RSS: Main: 262393856 18:34:39 INFO - PROCESS | 2234 | 18:34:42 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:62370/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:62370/tests/kraken/driver.html?testName=audio-fft) 18:34:42 INFO - PROCESS | 2234 | RSS: Main: 262516736 18:34:42 INFO - PROCESS | 2234 | 18:34:45 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:62370/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:62370/tests/kraken/driver.html?testName=audio-oscillator) 18:34:45 INFO - PROCESS | 2234 | RSS: Main: 263323648 18:34:45 INFO - PROCESS | 2234 | 18:34:47 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:62370/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:62370/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:34:48 INFO - PROCESS | 2234 | RSS: Main: 263651328 18:34:48 INFO - PROCESS | 2234 | 18:34:51 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:62370/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:62370/tests/kraken/driver.html?testName=imaging-darkroom) 18:34:51 INFO - PROCESS | 2234 | RSS: Main: 265236480 18:34:51 INFO - PROCESS | 2234 | 18:34:54 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:62370/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:62370/tests/kraken/driver.html?testName=imaging-desaturate) 18:34:55 INFO - PROCESS | 2234 | RSS: Main: 265551872 18:34:55 INFO - PROCESS | 2234 | 18:34:58 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:62370/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:62370/tests/kraken/driver.html?testName=json-parse-financial) 18:34:59 INFO - PROCESS | 2234 | RSS: Main: 265498624 18:34:59 INFO - PROCESS | 2234 | 18:35:00 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:62370/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:62370/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:35:01 INFO - PROCESS | 2234 | RSS: Main: 265379840 18:35:01 INFO - PROCESS | 2234 | 18:35:03 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:62370/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:62370/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:35:04 INFO - PROCESS | 2234 | RSS: Main: 265383936 18:35:04 INFO - PROCESS | 2234 | 18:35:06 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:62370/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:62370/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:35:07 INFO - PROCESS | 2234 | RSS: Main: 265428992 18:35:07 INFO - PROCESS | 2234 | 18:35:10 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:62370/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:62370/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:35:10 INFO - PROCESS | 2234 | RSS: Main: 265768960 18:35:10 INFO - PROCESS | 2234 | 18:35:13 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:62370/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:62370/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:35:14 INFO - PROCESS | 2234 | RSS: Main: 265555968 18:35:14 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:62370/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:62370/tests/kraken/driver.html?testName=ai-astar) 18:35:16 INFO - PROCESS | 2234 | RSS: Main: 267227136 18:35:16 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | __start_tp_report 18:35:16 INFO - PROCESS | 2234 | _x_x_mozilla_page_load 18:35:16 INFO - PROCESS | 2234 | _x_x_mozilla_page_load_details 18:35:16 INFO - PROCESS | 2234 | |i|pagename|runs| 18:35:16 INFO - PROCESS | 2234 | |0;ai-astar;140;118;117;169;109;166;178;164;117;118 18:35:16 INFO - PROCESS | 2234 | |1;audio-beat-detection;147;127;117;153;117;150;147;170;142;115 18:35:16 INFO - PROCESS | 2234 | |2;audio-dft;208;219;197;193;200;195;203;194;197;194 18:35:16 INFO - PROCESS | 2234 | |3;audio-fft;83;59;50;81;88;84;86;59;77;83 18:35:16 INFO - PROCESS | 2234 | |4;audio-oscillator;85;70;87;70;74;63;117;56;75;120 18:35:16 INFO - PROCESS | 2234 | |5;imaging-gaussian-blur;93;122;90;95;78;133;79;130;137;125 18:35:16 INFO - PROCESS | 2234 | |6;imaging-darkroom;94;100;155;123;95;95;95;95;109;155 18:35:16 INFO - PROCESS | 2234 | |7;imaging-desaturate;96;95;114;96;95;93;95;97;93;150 18:35:16 INFO - PROCESS | 2234 | |8;json-parse-financial;55;57;57;55;60;60;58;58;56;58 18:35:16 INFO - PROCESS | 2234 | |9;json-stringify-tinderbox;69;73;68;94;114;105;114;67;85;108 18:35:16 INFO - PROCESS | 2234 | |10;stanford-crypto-aes;87;88;90;91;90;87;90;89;91;102 18:35:16 INFO - PROCESS | 2234 | |11;stanford-crypto-ccm;142;155;143;146;152;144;161;145;202;185 18:35:16 INFO - PROCESS | 2234 | |12;stanford-crypto-pbkdf2;171;172;170;174;176;207;168;154;218;246 18:35:16 INFO - PROCESS | 2234 | |13;stanford-crypto-sha256-iterative;74;85;76;71;71;61;81;89;90;81 18:35:16 INFO - PROCESS | 2234 | __end_tp_report 18:35:16 INFO - PROCESS | 2234 | __start_cc_report 18:35:16 INFO - PROCESS | 2234 | _x_x_mozilla_cycle_collect,949 18:35:16 INFO - PROCESS | 2234 | __end_cc_report 18:35:16 INFO - PROCESS | 2234 | __startTimestamp1475285716432__endTimestamp 18:35:16 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | ------- Summary: start ------- 18:35:16 INFO - PROCESS | 2234 | Number of tests: 14 18:35:16 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | [#0] ai-astar Cycles:10 Average:139.60 Median:152.00 stddev:26.92 (17.7%) stddev-sans-first:28.55 18:35:16 INFO - PROCESS | 2234 | Values: 140.0 118.0 117.0 169.0 109.0 166.0 178.0 164.0 117.0 118.0 18:35:16 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | [#1] audio-beat-detection Cycles:10 Average:138.50 Median:147.00 stddev:18.56 (12.6%) stddev-sans-first:19.43 18:35:16 INFO - PROCESS | 2234 | Values: 147.0 127.0 117.0 153.0 117.0 150.0 147.0 170.0 142.0 115.0 18:35:16 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | [#2] audio-dft Cycles:10 Average:200.00 Median:198.50 stddev:8.15 (4.1%) stddev-sans-first:8.12 18:35:16 INFO - PROCESS | 2234 | Values: 208.0 219.0 197.0 193.0 200.0 195.0 203.0 194.0 197.0 194.0 18:35:16 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | [#3] audio-fft Cycles:10 Average:75.00 Median:83.00 stddev:13.65 (16.4%) stddev-sans-first:14.16 18:35:16 INFO - PROCESS | 2234 | Values: 83.0 59.0 50.0 81.0 88.0 84.0 86.0 59.0 77.0 83.0 18:35:16 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | [#4] audio-oscillator Cycles:10 Average:81.70 Median:72.00 stddev:21.45 (29.8%) stddev-sans-first:22.72 18:35:16 INFO - PROCESS | 2234 | Values: 85.0 70.0 87.0 70.0 74.0 63.0 117.0 56.0 75.0 120.0 18:35:16 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | [#5] imaging-gaussian-blur Cycles:10 Average:108.20 Median:78.50 stddev:23.32 (29.7%) stddev-sans-first:24.08 18:35:16 INFO - PROCESS | 2234 | Values: 93.0 122.0 90.0 95.0 78.0 133.0 79.0 130.0 137.0 125.0 18:35:16 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | [#6] imaging-darkroom Cycles:10 Average:111.60 Median:94.50 stddev:24.61 (26.0%) stddev-sans-first:25.26 18:35:16 INFO - PROCESS | 2234 | Values: 94.0 100.0 155.0 123.0 95.0 95.0 95.0 95.0 109.0 155.0 18:35:16 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | [#7] imaging-desaturate Cycles:10 Average:102.40 Median:95.00 stddev:17.80 (18.7%) stddev-sans-first:18.73 18:35:16 INFO - PROCESS | 2234 | Values: 96.0 95.0 114.0 96.0 95.0 93.0 95.0 97.0 93.0 150.0 18:35:16 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:1.78 (3.1%) stddev-sans-first:1.66 18:35:16 INFO - PROCESS | 2234 | Values: 55.0 57.0 57.0 55.0 60.0 60.0 58.0 58.0 56.0 58.0 18:35:16 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | [#9] json-stringify-tinderbox Cycles:10 Average:89.70 Median:68.50 stddev:19.68 (28.7%) stddev-sans-first:19.39 18:35:16 INFO - PROCESS | 2234 | Values: 69.0 73.0 68.0 94.0 114.0 105.0 114.0 67.0 85.0 108.0 18:35:16 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | [#10] stanford-crypto-aes Cycles:10 Average:90.50 Median:90.00 stddev:4.30 (4.8%) stddev-sans-first:4.37 18:35:16 INFO - PROCESS | 2234 | Values: 87.0 88.0 90.0 91.0 90.0 87.0 90.0 89.0 91.0 102.0 18:35:16 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | [#11] stanford-crypto-ccm Cycles:10 Average:157.50 Median:153.50 stddev:20.29 (13.2%) stddev-sans-first:20.74 18:35:16 INFO - PROCESS | 2234 | Values: 142.0 155.0 143.0 146.0 152.0 144.0 161.0 145.0 202.0 185.0 18:35:16 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.60 Median:175.00 stddev:28.54 (16.3%) stddev-sans-first:29.78 18:35:16 INFO - PROCESS | 2234 | Values: 171.0 172.0 170.0 174.0 176.0 207.0 168.0 154.0 218.0 246.0 18:35:16 INFO - PROCESS | 2234 | 18:35:16 INFO - PROCESS | 2234 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.90 Median:81.00 stddev:9.06 (11.2%) stddev-sans-first:9.50 18:35:16 INFO - PROCESS | 2234 | Values: 74.0 85.0 76.0 71.0 71.0 61.0 81.0 89.0 90.0 81.0 18:35:16 INFO - PROCESS | 2234 | -------- Summary: end -------- 18:35:16 INFO - PROCESS | 2234 | 18:35:17 INFO - TEST-INFO | 2234: exit 0 18:35:17 INFO - TEST-OK | kraken | took 61474ms 18:35:17 INFO - SUITE-END | took 1175s 18:35:17 INFO - Completed test suite (00:19:35) 18:35:17 INFO - javascript benchmark 18:35:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7350.248656369299, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3217.903462896113, 3322.4019676458256, 3333.266681330107, 3340.2317466982595, 3358.5465962095127, 4082.7958602069925, 5705.429457054298, 5725.885482290357, 5729.885402291951, 5755.827325180241, 5573.185541301647, 5698.031334673105, 5760.135979603051, 5782.3639399666, 5805.448482394177, 5148.691078535281, 5234.528892399683, 5252.317198764155, 5261.447548007462, 5276.736163191844, 5225.163973764198, 5285.4450282720145, 5302.867428314285, 5308.814191503298, 5389.021217523297, 4406.669499787513, 5151.330327057496, 5260.736963151865, 5296.311479507678, 5338.733063346836, 5177.301064356339, 5221.634485586011, 5251.0, 5259.342582177228, 5294.814681486129, 5192.454792246797, 5197.922031169498, 5211.42674305826, 5244.79020839166, 5270.288511051036, 4418.403515525395, 5655.773769049274, 5682.062459694145, 5688.175214593902, 5787.189793428924, 6118.893302669174, 6134.723937422782, 6163.476104531121, 6236.064590311445, 6246.0630905364105, 6225.190725205694, 6226.470749986257, 6237.812865614039, 6242.563020588561, 6259.405356491172, 4728.290756386536, 5725.627834190765, 5831.620944638585, 5845.240118784531, 5846.269216347957, 6193.1329613854095, 6199.535034872402, 6233.0, 6278.0, 6326.0, 6156.569040167145, 6172.444479996821, 6182.072689096672, 6189.6286222826775, 6193.349698281616, 6144.293406258248, 6181.0, 6245.250569931546, 6260.46786023184, 6263.812085637438, 6116.847078823065, 6119.938800612026, 6142.938570614237, 6146.832101900624, 6191.659458729723, 6185.659788711664, 6221.875562488815, 6224.62652240867, 6227.501799856001, 6242.344553821874, 4928.063667903087, 4930.235813448921, 4940.036692844933, 4942.357493525818, 4961.801527938915, 4350.586694264103, 4357.237483440449, 4412.801423935931, 4474.373587697725, 4484.0583477469445, 5868.530517558671, 5881.500072493886, 5927.0, 5977.820665380045, 5998.910016349758, 6241.500679945593, 6247.437730604177, 6264.0, 6269.373062693785, 6275.843103922439, 5803.216565763568, 5877.0, 5944.0, 5975.43233392827, 5981.37195594465, 5391.05656510117, 5397.568194544427, 5440.401555828856, 5445.0, 5494.478024587658, 6345.206849143857, 6366.872662546723, 6384.6169229846355, 6397.616143031433, 6409.743610255631, 6160.13758073861, 6181.907271390843, 6243.640965828614, 6254.967930291451, 6271.153394291712, 6240.438360547572, 6245.219347581552, 6298.1812364392335, 6301.4328710416285, 6348.746050158126, 5094.337736094432, 6192.659403732838, 6203.2245969253845, 6215.65813880241, 6224.1597384352535, 5138.768755405941, 5650.85872853182, 6084.6349219047, 6242.843928901813, 6255.968720156552, 5012.523810238022, 5932.317783454958, 6055.455009049207, 6080.939190608037, 6121.0206366981065, 5535.7785688572, 5737.770489180553, 5999.790007349722, 6186.845328866814, 6191.81424557264, 4608.400907882088, 5920.230370051866, 5987.31145918225, 6077.4226448487325, 6091.573589848846, 5154.278401023785, 6089.421504957022, 6107.85643655375, 6118.724657390517, 6194.0708893666315, 6023.668698221464, 6028.87942241144, 6036.456718895321, 6038.728257228523, 6050.273967123973], "value": 5659.4712288110195, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.76125556593917, 507.37592760904084, 523.3432042786304, 528.896865111303, 532.7241257189031, 39772.60227397725, 41218.0, 41244.38133428001, 43094.569054309526, 43112.56887431125, 41822.58177418225, 42375.57624423755, 42457.150856982844, 43217.0, 43242.783786081105, 9823.508824558798, 9987.650432234874, 10016.148627366683, 10017.348872323311, 10056.0, 9796.363236389612, 9902.207823374149, 9908.504574771232, 9919.0, 9920.156786673142, 9451.95274023629, 9889.10998010179, 9932.35439696421, 9932.453715045669, 9952.054554817281, 9867.309288349818, 9867.753306167331, 9889.505524723734, 9889.90110098897, 9892.653757118538, 9748.95125524375, 9829.80340393188, 9832.508374581314, 9843.261755368376, 9848.60605575776, 39130.0, 39838.203235935405, 40166.59833401658, 40596.0, 40721.981950451176, 3236.4659831127838, 3256.4138455078078, 3324.401607710611, 3340.532325474411, 3725.2735716535285, 29003.41993160146, 30894.22764430885, 30921.226969325722, 30953.499765049368, 31037.534436983464, 3192.138122706856, 3330.833458327074, 3363.0, 3439.449688049925, 3443.966809957028, 486053.0, 488508.0, 489830.0, 491516.0, 492652.0, 60420.69789651024, 65090.67454662791, 66107.00839487411, 66192.0, 66371.33628663652, 64909.350906490334, 64925.67537162284, 65525.0, 69408.65295673489, 69571.08645765166, 16252.67494650124, 16488.422905198262, 16501.669966600602, 16502.092384918706, 16516.339346426248, 12542.0, 12693.746125077447, 12705.364731763375, 12713.87286127127, 12848.614541563767, 16699.0, 19693.803061969484, 20385.796142038686, 20422.69365959512, 20496.487587810127, 8279.751607451786, 8300.750977470685, 8469.872951905727, 8477.533735644598, 8492.787680308042, 859.7291853066264, 941.2517048946104, 951.8714973478492, 1024.574801457387, 1074.4735079810837, 29551.965681201054, 29711.0, 30180.0, 30305.69694303073, 30401.743696438127, 907.7140700679397, 1041.313072822033, 1068.792264740838, 1085.473545330514, 1090.825467925144, 13810.895128389704, 15028.647421732097, 15564.299606517734, 15667.764983525256, 15888.2055897207, 571708.0, 650931.0, 666106.0, 668992.0, 672168.0, 51833.7408312956, 55825.0, 55937.16094258589, 55949.720251399296, 56052.71973640106, 1096.9561217551368, 1340.7921772125337, 1377.4903285784123, 1378.5588611644177, 1396.3506969259342, 28402.289942751595, 28525.857370713013, 29564.852175738983, 29996.700033000263, 30097.69902300949, 36707.81646091752, 45773.54226457693, 48632.5136748628, 48672.26991595058, 48713.53859384224, 571978.0, 572256.0, 573532.0, 574907.0, 578430.0, 34179.0, 40688.3896741555, 42153.78923105365, 42488.0, 42635.0, 45787.77106114582, 46422.30366544437, 46935.53064469311, 47426.52573474221, 47483.575492735275, 37215.62784372122, 37464.812675936446, 38402.2319553613, 38547.0, 38565.843024709306, 34023.48964765481, 35452.64547354494, 38453.0, 38895.611043890334, 39822.2035559293, 32254.19364515906, 32869.67130328666, 37249.441258380604, 37480.0, 38002.429963550014, 32218.83890580532, 33651.0, 34935.65064349324, 35039.47440788441, 35087.0, 25522.979080836558, 25760.355991099623, 25790.871045644653, 26084.34789130287, 26393.472130557664], "value": 20324.14880560816, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.52700543304564, 372.0290042987802, 378.92232092421045, 381.94652748615215, 400.6234139908486, 16981.83018169818, 18729.438116856498, 20334.08496617652, 20528.692069618966, 20723.378298651027, 20520.89739551304, 20541.79458205421, 20715.06782194801, 20798.16807327705, 20975.119602873478, 5298.6555873868265, 5484.533814625742, 5855.121731740241, 6003.0395136778125, 6006.099085137221, 5108.361454818148, 5954.851128721795, 6081.6351018939, 6085.939140608603, 6100.176476175704, 5715.571332150084, 5881.4118588141055, 5911.0, 5939.376365481627, 6058.454739073482, 5705.972924873521, 5887.735051922674, 5929.792457264019, 5947.6134051286745, 5972.6715030673295, 5119.308893299394, 5152.458991805844, 5162.6386152968935, 5173.534381905609, 5181.326427564431, 24928.12751553705, 25022.624660630107, 25133.62299565508, 25317.24048278536, 25452.87273563639, 2359.0210062823908, 2373.905043798246, 3010.9397812043635, 3074.1546074829375, 3084.6452657944174, 14350.210738409383, 15319.617009574738, 15444.5366639001, 15469.845301546953, 15712.842871571253, 1573.5200763767045, 1849.3804575467234, 1870.635226130905, 1900.515368581017, 1912.2733361322607, 116375.0, 116619.0, 116882.0, 117139.0, 119651.0, 9258.629654813732, 10286.428392901775, 10487.580496780196, 10689.35863848156, 10954.616588419367, 12965.805512917314, 13037.739245215043, 13045.739085218242, 13051.804222936664, 13068.803967940488, 8052.677892884336, 8280.213379729037, 8285.25432711059, 8428.241458268785, 8444.957775211085, 8370.53962032094, 8390.706325278587, 8397.916020839713, 8580.056193818675, 8622.741317760478, 4965.851024469272, 5367.570594352443, 5678.346990096192, 6179.721912513947, 6406.199225096863, 1643.8191798902112, 1819.6178802451634, 1859.4235786906102, 1862.5529872830423, 1867.486441228673, 733.9339459448676, 993.5429702336861, 999.9600015999424, 1012.3622118065572, 1044.5769463367349, 20695.8965205173, 21175.0, 21177.788222117582, 21402.78597214008, 21827.454313642287, 979.3438396274513, 980.3823591137538, 992.8212921674168, 1007.5012868630075, 1021.8262895307815, 10969.23215374916, 11641.068714502819, 11642.0, 11674.299542027336, 11741.823872641917, 551957.2402137964, 577962.0, 581914.0, 586837.0, 596366.0, 117916.0, 121655.0, 123102.76897230913, 123335.0, 124548.0, 1001.9248556358302, 1023.5752162852482, 1030.4487099401633, 1047.8742550893942, 1048.4862417415563, 18542.536436589195, 19327.0, 19336.323228686928, 19393.8060619392, 19396.515087122934, 46737.29894051524, 47198.05603887834, 48191.5180848187, 51817.74091129671, 52190.73904630452, 66134.66932665306, 67294.66352668402, 67453.66273168799, 67570.6350277738, 68000.6599966997, 7132.179799322937, 7442.553446793209, 7681.233316337385, 7752.418568607264, 7818.218178182136, 6962.930370696228, 9860.506974651094, 10057.798844023226, 10403.271770975958, 11843.703907402383, 9135.680251191176, 11028.22802403824, 11213.495392707511, 11269.0, 11354.375509347065, 10434.530446130095, 10601.0, 10811.405372704263, 10821.458927053458, 11261.380624065427, 9994.750131246778, 10507.318828987325, 10652.57389704403, 11393.487293071666, 11719.941400293284, 7744.651490683045, 7869.780184071478, 7926.841463170589, 7945.48354356965, 7978.0, 10759.838602420814, 11088.390138542458, 11319.660410187707, 11432.199746017699, 11634.301941883514], "value": 9546.373817606038, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1534.6700459401227, 1576.0307410942269, 1786.9821301786985, 1871.3824437935482, 1892.063428602841, 8067.636956336964, 8340.74977750667, 8590.48457092575, 9134.36059475837, 9172.266218702505, 8521.531315777629, 8850.867236991435, 8882.911170888288, 8951.865722014176, 8953.194212520872, 1940.9417717468464, 2366.526694661066, 2401.135591187171, 2401.4716762312264, 2410.240774156144, 2002.5294055896852, 2333.4866329407505, 2376.869272190029, 2393.198278576679, 2411.360989337818, 2362.444825466023, 2387.9641805372935, 2391.234804862445, 2391.3184742348435, 2407.626817843237, 2373.821963352747, 2375.489269807, 2379.583572874749, 2393.652920326552, 2403.4231784371623, 2347.882605869717, 2351.7883390494767, 2355.222776483756, 2359.775821296974, 2361.669366288721, 8910.955445222733, 8988.685396011173, 9013.459192448474, 9035.774105647346, 9038.81922361549, 5249.396319423277, 5279.366476022902, 5285.07511185541, 5332.786688532454, 5370.624056316059, 3497.6502349765074, 3620.9637903621156, 3642.1258897864323, 3693.6675699187203, 3706.499622550948, 1064.893510648929, 1099.8460215569746, 1108.9001989820874, 1124.5558004588142, 1125.58915995661, 11721.238119522204, 11732.706682332924, 11829.763404731943, 11858.881411185865, 11875.0, 4334.523202447728, 4504.3919070925, 4766.97510035344, 4792.113459010095, 4793.424789025269, 8574.914250857537, 9417.90582094184, 10033.548490317953, 10101.646442374482, 10134.54394552247, 5323.627346085815, 5327.094393953037, 5333.653312534673, 5342.1185504392115, 5342.626016178908, 4397.274449715825, 4564.360989461478, 4759.190937540627, 4881.511848815161, 4888.7311197884455, 3892.844286228519, 3961.6926414283216, 4034.23349563582, 4041.2927737645796, 4100.261952848516, 1281.8910392616544, 1284.800855867342, 1288.6005338345144, 1296.0215037646517, 1302.5115581656878, 870.9303255739652, 887.0951629338039, 890.4835195586544, 905.3345790843724, 914.3919293669768, 5691.857703557444, 5727.3699893011735, 5868.501177399969, 5919.93441180583, 6006.069059295816, 424.24907912994445, 427.79465856389106, 430.59954242554727, 440.04949309491343, 441.77690266415254, 3323.2026306611433, 3568.50040994261, 3722.218334149859, 3747.2318174774105, 3885.834249725071, 10559.155267578575, 10843.295185812896, 10921.726956826144, 10931.726706832234, 10993.955574220436, 10879.945600272107, 10901.182411319183, 10903.400312982862, 10921.672349829518, 10926.890731092588, 8237.093919668832, 8335.708250211335, 8343.874841877256, 8356.164383561716, 8363.707270245512, 4669.39297891272, 5545.1682247663175, 5585.832425027255, 5728.684922329312, 5794.304683438015, 9866.457344846101, 10048.899511004796, 10051.246156538142, 10076.042775936274, 10111.949440252753, 9724.124828765445, 10181.338213016426, 10215.336003159771, 10251.12865406419, 10259.794804104025, 7006.52862898797, 7185.389241914496, 7235.204127546071, 7475.028246327943, 7673.271039251262, 8046.114927358103, 8164.061132969785, 8302.916970830214, 8328.375371847014, 8333.208345207195, 7288.927110728825, 7402.148752893486, 7409.592472414069, 7443.627818609156, 7531.774046778605, 7209.567425954459, 7293.124825021031, 7358.190599034209, 7548.811279718051, 7560.130584982798, 6662.167229096363, 6718.832029199114, 6765.1205343305055, 6768.627725475146, 6781.966090169518, 4937.382827146606, 4962.950370496394, 4966.478519755374, 5007.098722229888, 5015.523525265094, 4867.050925069578, 4896.069746748107, 4982.7259500727805, 5108.540231379194, 5109.131447653834], "value": 4493.004904937246, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.95620656901474, 302.14643631740336, 309.82649716158954, 312.3004469987229, 315.5881574545219, 5237.0, 5486.890262194754, 5720.742566584503, 5748.109043098321, 5826.592138550303, 5577.8605534861645, 5600.0, 5688.60179787414, 5712.628679135863, 5720.768900128953, 4036.677065834741, 4293.36851996241, 4308.870733877989, 4332.06860524987, 4332.28517294646, 3363.0, 4069.97965010176, 4268.90504384322, 4331.956680433203, 4338.436003319564, 3812.1803812180374, 3909.43313219583, 4174.624283814456, 4249.936250956238, 4257.0, 3848.1918797052654, 4243.27864263076, 4257.936130958038, 4258.040661740092, 4265.274903266452, 3035.468792961245, 3304.7686661933676, 3466.8039526363364, 3556.6798988091195, 3668.6514781095752, 5300.655457395257, 5495.917561236584, 5556.805511807067, 5613.550915926716, 5643.661380317194, 4866.318715379814, 4887.75163424825, 4895.192293271606, 4901.0, 4909.68087074339, 4812.5668689818085, 4939.036887806879, 5894.882102357972, 6031.125486804389, 6058.0, 3738.056210770384, 3745.831437585315, 3965.4448377227213, 3979.343408337621, 4057.9594204057876, 5608.943910560883, 6159.722812473449, 6283.654399008053, 6305.369463053704, 6308.779192728279, 3495.528103706018, 4123.8556650517085, 4130.731502452331, 4142.979285103555, 4144.41978123063, 3498.9825050875093, 3703.9814800925824, 4207.705460617789, 4222.5087198553, 4239.618434340862, 4384.4519435070615, 4432.024954510002, 4526.913540750196, 4925.335079764258, 4925.655204135676, 5197.7401129943355, 5463.836084917459, 5795.536357091422, 5990.31111422192, 6044.697765111727, 1321.6365499487526, 1404.7963045358365, 1420.7229590229806, 1435.7846323051626, 1438.5828109848237, 756.3797685897512, 821.2403526737744, 840.1556435781999, 878.7846977490428, 889.3285569395065, 3900.863469778544, 4142.896427589274, 4346.0, 4427.02997166258, 4760.309755085494, 4452.799624016927, 4622.583967442946, 5224.7126408047925, 5227.817026404057, 5230.686158830483, 4251.659867210616, 4543.159515489645, 4687.648426368036, 4747.477777444478, 4808.735519546389, 2162.243214874781, 2202.8898555072183, 2511.9246422607353, 2612.6603541539857, 2690.0719251858077, 4591.494935557086, 4605.858275901722, 4775.158780525581, 5383.488568585978, 5401.297831281908, 4558.156741002925, 4602.12367270456, 4611.463694967754, 4718.952810471851, 5309.495597918191, 4503.076869241798, 4777.4267087949665, 5182.222666600041, 5208.270842082112, 5402.082875247323, 4603.700759450625, 4649.604783593432, 4833.830815921426, 4839.128956787671, 5219.817306394258, 4384.539623339631, 4457.665675074318, 4655.464621568428, 4876.536729010738, 5116.565091967075, 4835.8065677372515, 5356.785728570813, 6070.180525628984, 6118.112873633209, 6290.364505228582, 3497.650234976482, 3943.250782351459, 3947.6052394760295, 4131.442255295497, 4171.683511608339, 5712.6286791358425, 5715.770849368666, 6163.935081747665, 6178.320384757583, 6210.130581718653, 5337.9466205339, 5741.45456181662, 5942.286925568959, 5957.3744756799915, 5965.001724422367, 5166.199239117943, 5637.126245431965, 5691.8861622768145, 5958.970205148947, 5978.790742323998, 4622.798072501107, 5187.302602646527, 5317.787288508571, 5575.289192737393, 5851.151583020353, 5942.58401911862, 5959.0763431668165, 5959.940400596112, 5965.701714914324, 6010.046734811292, 5327.680339179662, 5664.830055098354, 5861.912071319019, 5867.295924488918, 5919.378465261086], "value": 4073.1060312965574, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.7113089297833, 660.7291010685619, 747.7980945144815, 787.5943888897216, 795.0737390939554, 13499.797503037462, 15154.545363639094, 15720.213989300546, 15946.883718139736, 16326.183690815456, 14260.215688137148, 14376.640583985405, 14396.064255823365, 14406.495772647959, 14406.783898241509, 678.8268991407184, 707.7275249029109, 834.2575108153748, 835.5446281776447, 866.2809867809734, 722.3679280629449, 863.7624653220383, 869.699953516036, 880.0759202837028, 887.9334049946248, 939.9248060155205, 942.2226663003015, 944.1549812917469, 944.2917811641269, 946.049220533363, 901.6483571407124, 931.4876817750244, 933.2953620016843, 936.1247233836317, 940.3041749105647, 645.7707513832569, 671.92944740802, 692.4287462843124, 697.9162500499973, 698.4901022253783, 11887.989520890735, 12856.292903890282, 14769.187694676788, 14909.179995100265, 14920.104793712304, 11954.641360759191, 12633.368331583475, 14507.492237771627, 14512.637184070378, 14711.264436778225, 10715.35707857531, 11791.23356981802, 11874.881251187464, 11878.940605297004, 12127.696807579792, 3785.9432108518395, 3802.429635554661, 3827.311084004878, 3858.4019476980834, 3962.366021436585, 112676.36618169058, 113989.0, 117488.0, 118581.40709296484, 119655.0, 9116.41934130895, 11046.447677616086, 11108.333499989865, 11269.09847212221, 11483.957551507287, 23211.419714507272, 23606.52786944286, 23627.7637223629, 23666.053357865494, 23671.7632823673, 7999.120096789348, 8214.424990250745, 8219.342452603778, 8222.876656850152, 8307.792305192419, 4280.76455794928, 4303.429248324339, 4410.757408342572, 4528.622049208937, 4647.953520464752, 3446.672566106216, 3538.3454060998797, 3562.4834399011997, 3575.588807287143, 3714.888553343404, 705.0587465733201, 705.2735682247294, 712.1952194020827, 725.3073314984283, 726.208432808231, 605.8243109498196, 657.4411750012547, 658.9341065893373, 680.7651360280695, 686.3994005245411, 8977.865332020025, 9512.619495220251, 9618.857617623748, 9666.75833104164, 9762.75593110178, 612.766466230587, 651.5046774057149, 668.7458765669112, 674.9021989070192, 698.0506511144839, 4352.948223877339, 4469.977650111794, 4672.509386514435, 4688.132695451352, 4821.879453013703, 142893.28553357167, 161160.0, 169564.0, 169869.0, 170264.4460333096, 134378.32810835884, 137282.0, 138592.0, 140862.0, 141211.29394352963, 72535.99999999894, 84260.0, 105049.47475262727, 106653.46673266584, 109056.0, 7277.235890231449, 8299.08710041907, 8959.955200223958, 9075.54622268897, 9090.727278181665, 52694.73652631712, 59628.40371596228, 59947.92861631762, 60710.69644651748, 61254.79334256638, 68862.0, 94196.0, 95998.0, 96491.035089651, 97972.51013744886, 11692.70768230801, 12434.813477798021, 12707.428165732379, 12955.481780729042, 13122.081454298108, 14574.927125364306, 14774.261286935825, 14846.331915064064, 14928.0, 15288.235588220323, 13558.93220533891, 13655.658608534866, 13713.520026799413, 13782.93108534451, 14056.297185140907, 10338.379697218192, 13796.034277600873, 14113.647158821112, 14302.57092287233, 14309.356078976263, 13344.799828002782, 13454.93272533631, 13478.0, 13663.453461861813, 13752.381142848395, 11037.227394082658, 11124.555017799197, 11377.317360958368, 11442.542298307975, 11722.120840937134, 10030.197584193102, 10920.78158436811, 10947.397893115636, 11416.143789215675, 11576.768464630828], "value": 7846.486189689877, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1615.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 118.0, 117.0, 169.0, 109.0, 166.0, 178.0, 164.0, 117.0, 118.0], "value": 139.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 127.0, 117.0, 153.0, 117.0, 150.0, 147.0, 170.0, 142.0, 115.0], "value": 138.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 219.0, 197.0, 193.0, 200.0, 195.0, 203.0, 194.0, 197.0, 194.0], "value": 200.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 50.0, 81.0, 88.0, 84.0, 86.0, 59.0, 77.0, 83.0], "value": 75.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 87.0, 70.0, 74.0, 63.0, 117.0, 56.0, 75.0, 120.0], "value": 81.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 122.0, 90.0, 95.0, 78.0, 133.0, 79.0, 130.0, 137.0, 125.0], "value": 108.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 100.0, 155.0, 123.0, 95.0, 95.0, 95.0, 95.0, 109.0, 155.0], "value": 111.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 114.0, 96.0, 95.0, 93.0, 95.0, 97.0, 93.0, 150.0], "value": 102.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 57.0, 55.0, 60.0, 60.0, 58.0, 58.0, 56.0, 58.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 68.0, 94.0, 114.0, 105.0, 114.0, 67.0, 85.0, 108.0], "value": 89.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 90.0, 91.0, 90.0, 87.0, 90.0, 89.0, 91.0, 102.0], "value": 90.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 155.0, 143.0, 146.0, 152.0, 144.0, 161.0, 145.0, 202.0, 185.0], "value": 157.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 170.0, 174.0, 176.0, 207.0, 168.0, 154.0, 218.0, 246.0], "value": 185.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 85.0, 76.0, 71.0, 71.0, 61.0, 81.0, 89.0, 90.0, 81.0], "value": 77.9, "unit": "score"}]}]} 18:35:17 INFO - Return code: 0 18:35:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:35:17 ERROR - # TBPL SUCCESS # 18:35:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:35:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:35:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:35:17 INFO - 2016-09-30 18:35:17.719 screenresolution[2239:37088] starting screenresolution argv=screenresolution get 18:35:17 INFO - 2016-09-30 18:35:17.723 screenresolution[2239:37088] Display 0: 1600x1200x32@60 18:35:17 INFO - 2016-09-30 18:35:17.729 screenresolution[2240:37091] starting screenresolution argv=screenresolution list 18:35:17 INFO - Available Modes on Display 0 18:35:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:35:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:35:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:35:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:35:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:35:17 INFO - Intel Iris: 18:35:17 INFO - Chipset Model: Intel Iris 18:35:17 INFO - Type: GPU 18:35:17 INFO - Bus: Built-In 18:35:17 INFO - VRAM (Dynamic, Max): 1536 MB 18:35:17 INFO - Vendor: Intel (0x8086) 18:35:17 INFO - Device ID: 0x0a2e 18:35:17 INFO - Revision ID: 0x0009 18:35:17 INFO - Displays: 18:35:17 INFO - Display: 18:35:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:35:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:35:17 INFO - Display Serial Number: 1600x1200 60 18:35:17 INFO - Main Display: Yes 18:35:17 INFO - Mirror: Off 18:35:17 INFO - Online: Yes 18:35:17 INFO - Rotation: Supported 18:35:17 INFO - Return code: 0 18:35:17 INFO - Running post-action listener: _resource_record_post_action 18:35:17 INFO - [mozharness: 2016-10-01 01:35:17.962236Z] Finished run-tests step (success) 18:35:17 INFO - Running post-run listener: _resource_record_post_run 18:35:18 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 947712; Write bytes: 710742528; Read time: 8; Write time: 1999 18:35:18 INFO - TinderboxPrint: CPU usage
54.6% 18:35:18 INFO - TinderboxPrint: I/O read bytes / time
947,712 / 8 18:35:18 INFO - TinderboxPrint: I/O write bytes / time
710,742,528 / 1,999 18:35:18 INFO - TinderboxPrint: CPU idle
2,199.8 (45.4%) 18:35:18 INFO - TinderboxPrint: CPU system
978.7 (20.2%) 18:35:18 INFO - TinderboxPrint: CPU user
1,663.3 (34.4%) 18:35:18 INFO - TinderboxPrint: Swap in / out
408,014,848 / 0 18:35:18 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399618560; Write bytes: 394851328; Read time: 29423; Write time: 804 18:35:18 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 947712; Write bytes: 299081216; Read time: 8; Write time: 1148 18:35:18 INFO - Running post-run listener: _upload_blobber_files 18:35:18 INFO - Blob upload gear active. 18:35:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:35:18 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 18:35:18 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:35:18 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:35:18 INFO - (blobuploader) - INFO - Open directory for files ... 18:35:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:35:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:35:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:35:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:35:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:35:19 INFO - (blobuploader) - INFO - Done attempting. 18:35:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:35:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:35:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:35:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:35:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:35:20 INFO - (blobuploader) - INFO - Done attempting. 18:35:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:35:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:35:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:35:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:35:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:35:24 INFO - (blobuploader) - INFO - Done attempting. 18:35:24 INFO - (blobuploader) - INFO - Iteration through files over. 18:35:24 INFO - Return code: 0 18:35:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:35:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:35:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0999235468b763e5743fc3678b01510a466deae68d32b9ab7c95b6154e6d6644674cfea79392e33169fefffee85723416c0168cb1f153107be5dd192bc0e2c26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56208a67bce849f0ec62cff357ed6b241d1a708e93eedd44346f0b3f817439d9c5550073661b322a4bbfe433d429753a3f0261b50d4cd129773e8d0da332f22c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/920ffc546b56f6f4c95ee206296e21356554d271c052bdb5e129fc94ae56bd28fc2b6da7b7ff71de38e3afe02736c664c1dd017a1a64a49f7a96d46387d15868"} 18:35:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:35:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:35:24 INFO - Contents: 18:35:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0999235468b763e5743fc3678b01510a466deae68d32b9ab7c95b6154e6d6644674cfea79392e33169fefffee85723416c0168cb1f153107be5dd192bc0e2c26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56208a67bce849f0ec62cff357ed6b241d1a708e93eedd44346f0b3f817439d9c5550073661b322a4bbfe433d429753a3f0261b50d4cd129773e8d0da332f22c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/920ffc546b56f6f4c95ee206296e21356554d271c052bdb5e129fc94ae56bd28fc2b6da7b7ff71de38e3afe02736c664c1dd017a1a64a49f7a96d46387d15868"} 18:35:24 INFO - Running post-run listener: copy_logs_to_upload_dir 18:35:24 INFO - Copying logs to upload dir... 18:35:24 INFO - mkdir: /builds/slave/test/build/upload/logs 18:35:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.739194 ========= master_lag: 2.88 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-09-30 18:35:27.663382) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-30 18:35:27.670382) ========= 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.fy7HxJy5Pd/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.yYLizVV8IC/Listeners TMPDIR=/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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/Autoland/sha512/0999235468b763e5743fc3678b01510a466deae68d32b9ab7c95b6154e6d6644674cfea79392e33169fefffee85723416c0168cb1f153107be5dd192bc0e2c26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56208a67bce849f0ec62cff357ed6b241d1a708e93eedd44346f0b3f817439d9c5550073661b322a4bbfe433d429753a3f0261b50d4cd129773e8d0da332f22c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/920ffc546b56f6f4c95ee206296e21356554d271c052bdb5e129fc94ae56bd28fc2b6da7b7ff71de38e3afe02736c664c1dd017a1a64a49f7a96d46387d15868"} build_url:https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011460 build_url: 'https://queue.taskcluster.net/v1/task/ThEwPGGuR-SeaqvYKBP5SA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0999235468b763e5743fc3678b01510a466deae68d32b9ab7c95b6154e6d6644674cfea79392e33169fefffee85723416c0168cb1f153107be5dd192bc0e2c26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56208a67bce849f0ec62cff357ed6b241d1a708e93eedd44346f0b3f817439d9c5550073661b322a4bbfe433d429753a3f0261b50d4cd129773e8d0da332f22c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/920ffc546b56f6f4c95ee206296e21356554d271c052bdb5e129fc94ae56bd28fc2b6da7b7ff71de38e3afe02736c664c1dd017a1a64a49f7a96d46387d15868"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-30 18:35:27.710060) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 18:35:27.710401) ========= 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.fy7HxJy5Pd/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.yYLizVV8IC/Listeners TMPDIR=/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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.005212 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 18:35:27.758756) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-30 18:35:27.762131) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-30 18:35:27.762496) ========= ========= Total master_lag: 3.14 =========