builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0130 starttime: 1475642304.75 results: success (0) buildid: 20161004195000 builduid: c97035c1b3cb45fe9a32fe92fd8b415a revision: d3366b063f5231867f2cea79ae229ac4b4e2c63b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 21:38:24.754644) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 21:38:24.755178) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 21:38:24.757543) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 21:38:24.785342) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 21:38:24.785847) ========= 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.mUfZphFmOo/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.PeQ01JIJuQ/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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-10-04 21:38:24-- 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% 152M=0s 2016-10-04 21:38:24 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123986 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 21:38:24.932036) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 21:38:24.932425) ========= 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.mUfZphFmOo/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.PeQ01JIJuQ/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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.020760 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 21:38:24.985800) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 21:38:24.986237) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d3366b063f5231867f2cea79ae229ac4b4e2c63b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev d3366b063f5231867f2cea79ae229ac4b4e2c63b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mUfZphFmOo/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.PeQ01JIJuQ/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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-10-04 21:38:24,886 truncating revision to first 12 chars 2016-10-04 21:38:24,886 Setting DEBUG logging. 2016-10-04 21:38:24,886 attempt 1/10 2016-10-04 21:38:24,886 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d3366b063f52?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-04 21:38:25,582 unpacking tar archive at: fx-team-d3366b063f52/testing/mozharness/ program finished with exit code 0 elapsedTime=0.944756 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 21:38:25.958448) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 21:38:25.958840) ========= script_repo_revision: d3366b063f5231867f2cea79ae229ac4b4e2c63b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 21:38:25.959358) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 21:38:25.959703) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 21:38:25.981255) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-04 21:38:25.981726) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mUfZphFmOo/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.PeQ01JIJuQ/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 21:38:25 INFO - MultiFileLogger online at 20161004 21:38:25 in /builds/slave/test 21:38:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 21:38:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:38:26 INFO - {'append_to_log': False, 21:38:26 INFO - 'base_work_dir': '/builds/slave/test', 21:38:26 INFO - 'blob_upload_branch': 'Fx-Team', 21:38:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:38:26 INFO - 'branch': 'Fx-Team', 21:38:26 INFO - 'buildbot_json_path': 'buildprops.json', 21:38:26 INFO - 'config_files': ('talos/mac_config.py',), 21:38:26 INFO - 'default_actions': ('clobber', 21:38:26 INFO - 'read-buildbot-config', 21:38:26 INFO - 'download-and-extract', 21:38:26 INFO - 'populate-webroot', 21:38:26 INFO - 'create-virtualenv', 21:38:26 INFO - 'install', 21:38:26 INFO - 'run-tests'), 21:38:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:38:26 INFO - 'download_minidump_stackwalk': True, 21:38:26 INFO - 'download_symbols': 'ondemand', 21:38:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:38:26 INFO - 'tooltool.py': '/tools/tooltool.py', 21:38:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:38:26 INFO - '/tools/misc-python/virtualenv.py')}, 21:38:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:38:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:38:26 INFO - 'installer_path': 'installer.exe', 21:38:26 INFO - 'log_level': 'info', 21:38:26 INFO - 'log_name': 'talos', 21:38:26 INFO - 'log_to_console': True, 21:38:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:38:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:38:26 INFO - 'opt_config_files': (), 21:38:26 INFO - 'pip_index': False, 21:38:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:38:26 INFO - 'cmd': ('bash', 21:38:26 INFO - '-c', 21:38:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:38:26 INFO - 'enabled': True, 21:38:26 INFO - 'halt_on_failure': False, 21:38:26 INFO - 'name': 'check_screen_resolution'},), 21:38:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:38:26 INFO - 'cmd': ('bash', 21:38:26 INFO - '-c', 21:38:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:38:26 INFO - 'enabled': True, 21:38:26 INFO - 'halt_on_failure': False, 21:38:26 INFO - 'name': 'check_screen_resolution'},), 21:38:26 INFO - 'run_cmd_checks_enabled': True, 21:38:26 INFO - 'sps_profile': False, 21:38:26 INFO - 'sps_profile_interval': 0, 21:38:26 INFO - 'suite': 'dromaeojs', 21:38:26 INFO - 'system_bits': '32', 21:38:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:38:26 INFO - 'title': 't-yosemite-r7-0130', 21:38:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:38:26 INFO - 'use_talos_json': True, 21:38:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:38:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:38:26 INFO - 'work_dir': 'build'} 21:38:26 INFO - [mozharness: 2016-10-05 04:38:26.009057Z] Running clobber step. 21:38:26 INFO - Running pre-action listener: _resource_record_pre_action 21:38:26 INFO - Running main action method: clobber 21:38:26 INFO - rmtree: /builds/slave/test/build 21:38:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:38:27 INFO - Running post-action listener: _resource_record_post_action 21:38:27 INFO - [mozharness: 2016-10-05 04:38:27.654480Z] Finished clobber step (success) 21:38:27 INFO - [mozharness: 2016-10-05 04:38:27.654740Z] Running read-buildbot-config step. 21:38:27 INFO - Running pre-action listener: _resource_record_pre_action 21:38:27 INFO - Running main action method: read_buildbot_config 21:38:27 INFO - Using buildbot properties: 21:38:27 INFO - { 21:38:27 INFO - "project": "", 21:38:27 INFO - "product": "firefox", 21:38:27 INFO - "script_repo_revision": "production", 21:38:27 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 21:38:27 INFO - "repository": "", 21:38:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 21:38:27 INFO - "buildid": "20161004195000", 21:38:27 INFO - "pgo_build": "False", 21:38:27 INFO - "basedir": "/builds/slave/test", 21:38:27 INFO - "buildnumber": 179, 21:38:27 INFO - "slavename": "t-yosemite-r7-0130", 21:38:27 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 21:38:27 INFO - "platform": "yosemite_r7", 21:38:27 INFO - "scheduler": "tests-fx-team-macosx64-talos", 21:38:27 INFO - "branch": "fx-team", 21:38:27 INFO - "repo_path": "integration/fx-team", 21:38:27 INFO - "revision": "d3366b063f5231867f2cea79ae229ac4b4e2c63b", 21:38:27 INFO - "stage_platform": "macosx64", 21:38:27 INFO - "builduid": "c97035c1b3cb45fe9a32fe92fd8b415a", 21:38:27 INFO - "slavebuilddir": "test" 21:38:27 INFO - } 21:38:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 21:38:27 INFO - Running post-action listener: _resource_record_post_action 21:38:27 INFO - [mozharness: 2016-10-05 04:38:27.660479Z] Finished read-buildbot-config step (success) 21:38:27 INFO - [mozharness: 2016-10-05 04:38:27.660674Z] Running download-and-extract step. 21:38:27 INFO - Running pre-action listener: _resource_record_pre_action 21:38:27 INFO - Running main action method: download_and_extract 21:38:27 INFO - mkdir: /builds/slave/test/build/tests 21:38:27 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')]} 21:38:27 INFO - trying https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 21:38:27 INFO - Downloading https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/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 21:38:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/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 21:38:28 INFO - Downloaded 1199 bytes. 21:38:28 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 21:38:28 INFO - Using the following test package requirements: 21:38:28 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 21:38:28 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:38:28 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 21:38:28 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:38:28 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 21:38:28 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:38:28 INFO - u'jsshell-mac.zip'], 21:38:28 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:38:28 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 21:38:28 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 21:38:28 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:38:28 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 21:38:28 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:38:28 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 21:38:28 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:38:28 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 21:38:28 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:38:28 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 21:38:28 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:38:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 21:38:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:38:28 INFO - Fetch https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 21:38:29 INFO - Http code: 200 21:38:29 INFO - via: 1.1 55676da1e5c0a9c4e60a94a95b01dc04.cloudfront.net (CloudFront) 21:38:29 INFO - x-amz-cf-id: U0oNZH4P7PmB3dS4LyleglkPPGEWeAtEgGuqRFMw5usWTjZrNjO-Pg== 21:38:29 INFO - x-amz-version-id: DuLRh4GpRPB_hudKsJLA11BV4RaV08aq 21:38:29 INFO - Expected file size: 16476869 21:38:29 INFO - Obtained file size: 16476869 21:38:36 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 21:38:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 21:38:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:38:36 INFO - Fetch https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 21:38:37 INFO - Http code: 200 21:38:37 INFO - via: 1.1 ff09df097f823b2834408d17e9779d62.cloudfront.net (CloudFront) 21:38:37 INFO - x-amz-cf-id: _TXldjebVIM-zsRv0vs2E2RoY6BKvVx89Fz9tuSdVE638cowFdzqCA== 21:38:37 INFO - x-amz-version-id: DuLRh4GpRPB_hudKsJLA11BV4RaV08aq 21:38:37 INFO - Expected file size: 16476869 21:38:37 INFO - Obtained file size: 16476869 21:38:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 21:38:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:38:44 INFO - Fetch https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 21:38:45 INFO - Http code: 200 21:38:45 INFO - via: 1.1 2239f0bfe6d7427183a4e375c4638619.cloudfront.net (CloudFront) 21:38:45 INFO - x-amz-cf-id: 6cARstqz831J0ZZAtJNclNdGmnjTGsqxNG0BOy2RcRG7zZaycR_eCg== 21:38:45 INFO - x-amz-version-id: 1PduFBbfUo6Uv7qhHEdNXvnymuZiS5jL 21:38:45 INFO - Expected file size: 14409006 21:38:45 INFO - Obtained file size: 14409006 21:38: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')]} 21:38:45 INFO - trying https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 21:38:45 INFO - Downloading https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 21:38:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/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 21:38:48 INFO - Downloaded 111717635 bytes. 21:38:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 21:38:48 INFO - mkdir: /builds/slave/test/properties 21:38:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:38:48 INFO - Writing to file /builds/slave/test/properties/build_url 21:38:48 INFO - Contents: 21:38:48 INFO - build_url:https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 21:38:49 INFO - Running post-action listener: _resource_record_post_action 21:38:49 INFO - Running post-action listener: set_extra_try_arguments 21:38:49 INFO - [mozharness: 2016-10-05 04:38:49.484985Z] Finished download-and-extract step (success) 21:38:49 INFO - [mozharness: 2016-10-05 04:38:49.485102Z] Running populate-webroot step. 21:38:49 INFO - Running pre-action listener: _resource_record_pre_action 21:38:49 INFO - Running main action method: populate_webroot 21:38:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:38:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:38:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:38:49 INFO - '--tppagecycles', 21:38:49 INFO - '7'], 21:38:49 INFO - 'tests': ['tsvgm']}}, 21:38:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:38:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:38:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:38:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:38:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:38:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:38:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:38:49 INFO - 'talos_options': ['--disable-e10s'], 21:38:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:38:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:38:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:38:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:38:49 INFO - 'talos_options': ['--disable-e10s'], 21:38:49 INFO - 'tests': ['damp', 'tps']}, 21:38:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:38:49 INFO - 'tests': ['damp', 'tps']}, 21:38:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:38:49 INFO - 'tests': ['dromaeo_dom']}, 21:38:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:38:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:38:49 INFO - 'tests': ['basic_compositor_video']}, 21:38:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:38:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:38:49 INFO - 'tests': ['a11yr', 21:38:49 INFO - 'ts_paint', 21:38:49 INFO - 'tpaint', 21:38:49 INFO - 'sessionrestore', 21:38:49 INFO - 'sessionrestore_no_auto_restore', 21:38:49 INFO - 'tabpaint']}, 21:38:49 INFO - 'other-e10s': {'tests': ['a11yr', 21:38:49 INFO - 'ts_paint', 21:38:49 INFO - 'tpaint', 21:38:49 INFO - 'sessionrestore', 21:38:49 INFO - 'sessionrestore_no_auto_restore', 21:38:49 INFO - 'tabpaint']}, 21:38:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:38:49 INFO - 'ts_paint', 21:38:49 INFO - 'tpaint', 21:38:49 INFO - 'sessionrestore', 21:38:49 INFO - 'sessionrestore_no_auto_restore', 21:38:49 INFO - 'tabpaint']}, 21:38:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:38:49 INFO - 'ts_paint', 21:38:49 INFO - 'tpaint', 21:38:49 INFO - 'sessionrestore', 21:38:49 INFO - 'sessionrestore_no_auto_restore', 21:38:49 INFO - 'tabpaint']}, 21:38:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:38:49 INFO - 'tests': ['a11yr', 21:38:49 INFO - 'ts_paint', 21:38:49 INFO - 'tpaint', 21:38:49 INFO - 'sessionrestore', 21:38:49 INFO - 'sessionrestore_no_auto_restore', 21:38:49 INFO - 'tabpaint']}, 21:38:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:38:49 INFO - 'tests': ['a11yr', 21:38:49 INFO - 'ts_paint', 21:38:49 INFO - 'tpaint', 21:38:49 INFO - 'sessionrestore', 21:38:49 INFO - 'sessionrestore_no_auto_restore', 21:38:49 INFO - 'tabpaint']}, 21:38:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:38:49 INFO - 'tests': ['tsvgx', 21:38:49 INFO - 'tsvgr_opacity', 21:38:49 INFO - 'tart', 21:38:49 INFO - 'tscrollx', 21:38:49 INFO - 'cart']}, 21:38:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:38:49 INFO - 'tsvgr_opacity', 21:38:49 INFO - 'tart', 21:38:49 INFO - 'tscrollx', 21:38:49 INFO - 'cart']}, 21:38:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:38:49 INFO - 'talos_options': ['--disable-e10s'], 21:38:49 INFO - 'tests': ['tp5o']}, 21:38:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:38:49 INFO - 'tests': ['tp5o']}, 21:38:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:38:49 INFO - 'talos_options': ['--disable-e10s', 21:38:49 INFO - '--xperf_path', 21:38:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:38:49 INFO - 'tests': ['tp5n']}, 21:38:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:38:49 INFO - 'talos_options': ['--xperf_path', 21:38:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:38:49 INFO - 'tests': ['tp5n']}}, 21:38:49 INFO - 'talos.zip': {'path': '', 21:38:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:38:49 INFO - Running post-action listener: _resource_record_post_action 21:38:49 INFO - [mozharness: 2016-10-05 04:38:49.504690Z] Finished populate-webroot step (success) 21:38:49 INFO - [mozharness: 2016-10-05 04:38:49.504817Z] Running create-virtualenv step. 21:38:49 INFO - Running pre-action listener: _resource_record_pre_action 21:38:49 INFO - Running main action method: create_virtualenv 21:38:49 INFO - Creating virtualenv /builds/slave/test/build/venv 21:38:49 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 21:38:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:38:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:38:49 INFO - Using real prefix '/tools/python27' 21:38:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:38:50 INFO - Installing distribute.............................................................................................................................................................................................done. 21:38:53 INFO - Installing pip.................done. 21:38:53 INFO - Return code: 0 21:38:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:38:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:38:53 INFO - Reading from file tmpfile_stdout 21:38:53 INFO - Output received: 21:38:53 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) 21:38:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:38:53 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')]} 21:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:53 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')]} 21:38:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:53 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 0x102069490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb4b584060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e7dd30>, '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 0x101ca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e75b20>, '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.mUfZphFmOo/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.PeQ01JIJuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 21:38:53 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 21:38:53 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 21:38:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mUfZphFmOo/Render', 21:38:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:38:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:38:53 INFO - 'HOME': '/Users/cltbld', 21:38:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:38:53 INFO - 'LOGNAME': 'cltbld', 21:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:53 INFO - 'MOZ_NO_REMOTE': '1', 21:38:53 INFO - 'NO_EM_RESTART': '1', 21:38:53 INFO - 'PAGER': '/bin/cat', 21:38:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:53 INFO - 'PWD': '/builds/slave/test', 21:38:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:38:53 INFO - 'SHELL': '/bin/bash', 21:38:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PeQ01JIJuQ/Listeners', 21:38:53 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 21:38:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:38:53 INFO - 'USER': 'cltbld', 21:38:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:38:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:53 INFO - 'XPC_FLAGS': '0x0', 21:38:53 INFO - 'XPC_SERVICE_NAME': '0', 21:38:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:38:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:53 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 21:38:53 INFO - Cleaning up... 21:38:53 INFO - Return code: 0 21:38:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:38:53 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')]} 21:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:53 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')]} 21:38:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:53 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 0x102069490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb4b584060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e7dd30>, '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 0x101ca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e75b20>, '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.mUfZphFmOo/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.PeQ01JIJuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 21:38:53 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 21:38:53 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 21:38:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mUfZphFmOo/Render', 21:38:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:38:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:38:53 INFO - 'HOME': '/Users/cltbld', 21:38:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:38:53 INFO - 'LOGNAME': 'cltbld', 21:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:53 INFO - 'MOZ_NO_REMOTE': '1', 21:38:53 INFO - 'NO_EM_RESTART': '1', 21:38:53 INFO - 'PAGER': '/bin/cat', 21:38:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:53 INFO - 'PWD': '/builds/slave/test', 21:38:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:38:53 INFO - 'SHELL': '/bin/bash', 21:38:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PeQ01JIJuQ/Listeners', 21:38:53 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 21:38:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:38:53 INFO - 'USER': 'cltbld', 21:38:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:38:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:53 INFO - 'XPC_FLAGS': '0x0', 21:38:53 INFO - 'XPC_SERVICE_NAME': '0', 21:38:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:38:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:53 INFO - Downloading/unpacking psutil>=3.1.1 21:38:53 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 21:38:53 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 21:38:53 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 21:38:53 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 21:38:53 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 21:38:53 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 21:38:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:38:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:38:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:38:57 INFO - Installing collected packages: psutil 21:38:57 INFO - Running setup.py install for psutil 21:38:57 INFO - building 'psutil._psutil_osx' extension 21:38:57 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 21:38:57 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 21:38:57 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 21:38:57 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 21:38:57 INFO - building 'psutil._psutil_posix' extension 21:38:57 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 21:38:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:38:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:38:57 INFO - ^ 21:38:58 INFO - 1 warning generated. 21:38:58 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 21:38:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:38:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:38:58 INFO - Successfully installed psutil 21:38:58 INFO - Cleaning up... 21:38:58 INFO - Return code: 0 21:38:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:38:58 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')]} 21:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:58 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')]} 21:38:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:58 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 0x102069490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb4b584060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e7dd30>, '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 0x101ca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e75b20>, '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.mUfZphFmOo/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.PeQ01JIJuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 21:38:58 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 21:38:58 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 21:38:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mUfZphFmOo/Render', 21:38:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:38:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:38:58 INFO - 'HOME': '/Users/cltbld', 21:38:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:38:58 INFO - 'LOGNAME': 'cltbld', 21:38:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:58 INFO - 'MOZ_NO_REMOTE': '1', 21:38:58 INFO - 'NO_EM_RESTART': '1', 21:38:58 INFO - 'PAGER': '/bin/cat', 21:38:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:38:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:58 INFO - 'PWD': '/builds/slave/test', 21:38:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:38:58 INFO - 'SHELL': '/bin/bash', 21:38:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PeQ01JIJuQ/Listeners', 21:38:58 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 21:38:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:38:58 INFO - 'USER': 'cltbld', 21:38:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:38:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:38:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:58 INFO - 'XPC_FLAGS': '0x0', 21:38:58 INFO - 'XPC_SERVICE_NAME': '0', 21:38:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:38:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:38:58 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 21:38:58 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 21:38:58 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 21:38:58 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 21:38:58 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 21:38:58 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 21:39:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:39:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:39:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:39:01 INFO - Installing collected packages: mozsystemmonitor 21:39:01 INFO - Running setup.py install for mozsystemmonitor 21:39:01 INFO - Successfully installed mozsystemmonitor 21:39:01 INFO - Cleaning up... 21:39:01 INFO - Return code: 0 21:39:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:39: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')]} 21:39:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39: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')]} 21:39:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:01 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 0x102069490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb4b584060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e7dd30>, '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 0x101ca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e75b20>, '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.mUfZphFmOo/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.PeQ01JIJuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 21:39:01 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 21:39:01 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 21:39:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mUfZphFmOo/Render', 21:39:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:01 INFO - 'HOME': '/Users/cltbld', 21:39:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:01 INFO - 'LOGNAME': 'cltbld', 21:39:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:01 INFO - 'MOZ_NO_REMOTE': '1', 21:39:01 INFO - 'NO_EM_RESTART': '1', 21:39:01 INFO - 'PAGER': '/bin/cat', 21:39:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:01 INFO - 'PWD': '/builds/slave/test', 21:39:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:01 INFO - 'SHELL': '/bin/bash', 21:39:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PeQ01JIJuQ/Listeners', 21:39:01 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 21:39:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:01 INFO - 'USER': 'cltbld', 21:39:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:01 INFO - 'XPC_FLAGS': '0x0', 21:39:01 INFO - 'XPC_SERVICE_NAME': '0', 21:39:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:01 INFO - Downloading/unpacking jsonschema==2.5.1 21:39:01 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 21:39:01 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 21:39:01 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 21:39:01 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 21:39:01 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 21:39:01 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 21:39:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:39:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:39: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 21:39: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 21:39: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 21:39: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 21:39: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 21:39: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 21:39:04 INFO - Downloading functools32-3.2.3-2.tar.gz 21:39:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:39:04 INFO - warning: no files found matching '*.txt' 21:39:04 INFO - no previously-included directories found matching 'build' 21:39:04 INFO - no previously-included directories found matching 'dist' 21:39:04 INFO - no previously-included directories found matching '.git*' 21:39:04 INFO - Installing collected packages: jsonschema, functools32 21:39:04 INFO - Running setup.py install for functools32 21:39:04 INFO - warning: no files found matching '*.txt' 21:39:04 INFO - no previously-included directories found matching 'build' 21:39:04 INFO - no previously-included directories found matching 'dist' 21:39:04 INFO - no previously-included directories found matching '.git*' 21:39:04 INFO - Successfully installed jsonschema functools32 21:39:04 INFO - Cleaning up... 21:39:04 INFO - Return code: 0 21:39:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:39:04 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')]} 21:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:04 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')]} 21:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:04 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 0x102069490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb4b584060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e7dd30>, '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 0x101ca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e75b20>, '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.mUfZphFmOo/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.PeQ01JIJuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 21:39:04 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 21:39:04 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 21:39:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mUfZphFmOo/Render', 21:39:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:04 INFO - 'HOME': '/Users/cltbld', 21:39:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:04 INFO - 'LOGNAME': 'cltbld', 21:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:04 INFO - 'MOZ_NO_REMOTE': '1', 21:39:04 INFO - 'NO_EM_RESTART': '1', 21:39:04 INFO - 'PAGER': '/bin/cat', 21:39:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:04 INFO - 'PWD': '/builds/slave/test', 21:39:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:04 INFO - 'SHELL': '/bin/bash', 21:39:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PeQ01JIJuQ/Listeners', 21:39:04 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 21:39:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:04 INFO - 'USER': 'cltbld', 21:39:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:04 INFO - 'XPC_FLAGS': '0x0', 21:39:04 INFO - 'XPC_SERVICE_NAME': '0', 21:39:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:39:05 INFO - Cleaning up... 21:39:05 INFO - Return code: 0 21:39:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:39:05 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')]} 21:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:05 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')]} 21:39:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:05 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 0x102069490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb4b584060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e7dd30>, '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 0x101ca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e75b20>, '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.mUfZphFmOo/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.PeQ01JIJuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 21:39:05 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 21:39:05 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 21:39:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mUfZphFmOo/Render', 21:39:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:05 INFO - 'HOME': '/Users/cltbld', 21:39:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:05 INFO - 'LOGNAME': 'cltbld', 21:39:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:05 INFO - 'MOZ_NO_REMOTE': '1', 21:39:05 INFO - 'NO_EM_RESTART': '1', 21:39:05 INFO - 'PAGER': '/bin/cat', 21:39:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:05 INFO - 'PWD': '/builds/slave/test', 21:39:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:05 INFO - 'SHELL': '/bin/bash', 21:39:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PeQ01JIJuQ/Listeners', 21:39:05 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 21:39:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:05 INFO - 'USER': 'cltbld', 21:39:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:05 INFO - 'XPC_FLAGS': '0x0', 21:39:05 INFO - 'XPC_SERVICE_NAME': '0', 21:39:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:05 INFO - Downloading/unpacking blobuploader==1.2.4 21:39:05 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 21:39:05 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 21:39:05 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 21:39:05 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 21:39:05 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 21:39:05 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 21:39:07 INFO - Downloading blobuploader-1.2.4.tar.gz 21:39:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:39:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:39:07 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 21:39:07 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 21:39:07 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 21:39:07 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 21:39:07 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 21:39:07 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 21:39:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:39:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:39:08 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 21:39:08 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 21:39:08 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 21:39:08 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 21:39:08 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 21:39:08 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 21:39:08 INFO - Downloading docopt-0.6.1.tar.gz 21:39:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:39:08 INFO - Installing collected packages: blobuploader, requests, docopt 21:39:08 INFO - Running setup.py install for blobuploader 21:39:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:39:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:39:09 INFO - Running setup.py install for requests 21:39:09 INFO - Running setup.py install for docopt 21:39:09 INFO - Successfully installed blobuploader requests docopt 21:39:09 INFO - Cleaning up... 21:39:09 INFO - Return code: 0 21:39:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:39:09 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')]} 21:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:09 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')]} 21:39:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:09 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 0x102069490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb4b584060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e7dd30>, '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 0x101ca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e75b20>, '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.mUfZphFmOo/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.PeQ01JIJuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 21:39:09 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 21:39:09 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 21:39:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mUfZphFmOo/Render', 21:39:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:09 INFO - 'HOME': '/Users/cltbld', 21:39:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:09 INFO - 'LOGNAME': 'cltbld', 21:39:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:09 INFO - 'MOZ_NO_REMOTE': '1', 21:39:09 INFO - 'NO_EM_RESTART': '1', 21:39:09 INFO - 'PAGER': '/bin/cat', 21:39:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:09 INFO - 'PWD': '/builds/slave/test', 21:39:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:09 INFO - 'SHELL': '/bin/bash', 21:39:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PeQ01JIJuQ/Listeners', 21:39:09 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 21:39:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:09 INFO - 'USER': 'cltbld', 21:39:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:09 INFO - 'XPC_FLAGS': '0x0', 21:39:09 INFO - 'XPC_SERVICE_NAME': '0', 21:39:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:39:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-Sy_P3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:39:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-KesMHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:39:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-koZauN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:39:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-kFmc5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:39:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-j2krdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:39:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-Y1Nyrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:39:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-Hm1vU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:39:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-b5DLdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:39:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-IU3rSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:39:11 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-PnyO36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:39:11 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-j5nFTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:39:11 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-zUv6Rc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:39:11 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-q9je0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:39:11 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-HX2QMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:39:11 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-dqmswg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:39:11 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-9zfGDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:39:11 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-86I9ZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:39:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:39:11 INFO - Running setup.py install for manifestparser 21:39:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:39:12 INFO - Running setup.py install for mozcrash 21:39:12 INFO - Running setup.py install for mozdebug 21:39:12 INFO - Running setup.py install for mozdevice 21:39:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:39:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:39:12 INFO - Running setup.py install for mozfile 21:39:12 INFO - Running setup.py install for mozhttpd 21:39:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:39:12 INFO - Running setup.py install for mozinfo 21:39:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:39:12 INFO - Running setup.py install for mozInstall 21:39:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:39:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:39:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:39:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:39:13 INFO - Running setup.py install for mozleak 21:39:13 INFO - Running setup.py install for mozlog 21:39:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:39:13 INFO - Running setup.py install for moznetwork 21:39:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:39:13 INFO - Running setup.py install for mozprocess 21:39:13 INFO - Running setup.py install for mozprofile 21:39:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:39:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:39:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:39:13 INFO - Running setup.py install for mozrunner 21:39:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:39:14 INFO - Running setup.py install for mozscreenshot 21:39:14 INFO - Running setup.py install for moztest 21:39:14 INFO - Running setup.py install for mozversion 21:39:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:39:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:39:14 INFO - Cleaning up... 21:39:14 INFO - Return code: 0 21:39:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:39:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:15 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')]} 21:39:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:15 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 0x102069490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb4b584060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e7dd30>, '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 0x101ca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e75b20>, '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.mUfZphFmOo/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.PeQ01JIJuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 21:39:15 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 21:39:15 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 21:39:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mUfZphFmOo/Render', 21:39:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:15 INFO - 'HOME': '/Users/cltbld', 21:39:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:15 INFO - 'LOGNAME': 'cltbld', 21:39:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:15 INFO - 'MOZ_NO_REMOTE': '1', 21:39:15 INFO - 'NO_EM_RESTART': '1', 21:39:15 INFO - 'PAGER': '/bin/cat', 21:39:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:15 INFO - 'PWD': '/builds/slave/test', 21:39:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:15 INFO - 'SHELL': '/bin/bash', 21:39:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PeQ01JIJuQ/Listeners', 21:39:15 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 21:39:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:15 INFO - 'USER': 'cltbld', 21:39:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:15 INFO - 'XPC_FLAGS': '0x0', 21:39:15 INFO - 'XPC_SERVICE_NAME': '0', 21:39:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:39:16 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-BzAxci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:39:16 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)) 21:39:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:39:16 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-PP2r2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:39:16 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)) 21:39:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:39:16 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-4GKhFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:39:16 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)) 21:39:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:39:16 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-WH4KhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:39:16 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)) 21:39:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:39:16 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-s1uHhW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:39:16 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)) 21:39:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:39:16 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-NrZHIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:39:16 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)) 21:39:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:39:16 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-HdNius-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:39:16 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)) 21:39:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:39:16 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-nomYJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:39:16 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)) 21:39:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:39:16 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-efpKTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:39:17 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)) 21:39:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:39:17 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-uoxVnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:39:17 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)) 21:39:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:39:17 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-G8ViZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:39:17 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)) 21:39:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:39:17 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-nQ29qA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:39:17 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)) 21:39:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:39:17 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-SP7CBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:39:17 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)) 21:39:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:39:17 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-nbnFpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:39:17 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)) 21:39:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:39:17 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-Un7Ln7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:39:17 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)) 21:39:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:39:17 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-BzYZGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:39:17 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)) 21:39:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:39:17 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-OI5VP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:39:17 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)) 21:39:17 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)) 21:39:17 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)) 21:39:17 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)) 21:39:17 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)) 21:39:17 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)) 21:39:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:39:17 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 21:39:17 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 21:39:17 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 21:39:17 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 21:39:17 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 21:39:17 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 21:39:20 INFO - Downloading blessings-1.6.tar.gz 21:39:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:39:20 INFO - Installing collected packages: blessings 21:39:20 INFO - Running setup.py install for blessings 21:39:20 INFO - Successfully installed blessings 21:39:20 INFO - Cleaning up... 21:39:20 INFO - Return code: 0 21:39:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:39:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:39:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:39:21 INFO - Reading from file tmpfile_stdout 21:39:21 INFO - Current package versions: 21:39:21 INFO - blessings == 1.6 21:39:21 INFO - blobuploader == 1.2.4 21:39:21 INFO - docopt == 0.6.1 21:39:21 INFO - functools32 == 3.2.3-2 21:39:21 INFO - jsonschema == 2.5.1 21:39:21 INFO - manifestparser == 1.1 21:39:21 INFO - mozInstall == 1.12 21:39:21 INFO - mozcrash == 0.17 21:39:21 INFO - mozdebug == 0.1 21:39:21 INFO - mozdevice == 0.48 21:39:21 INFO - mozfile == 1.2 21:39:21 INFO - mozhttpd == 0.7 21:39:21 INFO - mozinfo == 0.9 21:39:21 INFO - mozleak == 0.1 21:39:21 INFO - mozlog == 3.4 21:39:21 INFO - moznetwork == 0.27 21:39:21 INFO - mozprocess == 0.23 21:39:21 INFO - mozprofile == 0.28 21:39:21 INFO - mozrunner == 6.12 21:39:21 INFO - mozscreenshot == 0.1 21:39:21 INFO - mozsystemmonitor == 0.3 21:39:21 INFO - moztest == 0.7 21:39:21 INFO - mozversion == 1.4 21:39:21 INFO - psutil == 3.1.1 21:39:21 INFO - requests == 1.2.3 21:39:21 INFO - wsgiref == 0.1.2 21:39:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:39:21 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')]} 21:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:21 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')]} 21:39:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:21 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 0x102069490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb4b584060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e7dd30>, '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 0x101ca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e75b20>, '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.mUfZphFmOo/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.PeQ01JIJuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 21:39:21 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 21:39:21 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 21:39:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mUfZphFmOo/Render', 21:39:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:21 INFO - 'HOME': '/Users/cltbld', 21:39:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:21 INFO - 'LOGNAME': 'cltbld', 21:39:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:21 INFO - 'MOZ_NO_REMOTE': '1', 21:39:21 INFO - 'NO_EM_RESTART': '1', 21:39:21 INFO - 'PAGER': '/bin/cat', 21:39:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:21 INFO - 'PWD': '/builds/slave/test', 21:39:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:21 INFO - 'SHELL': '/bin/bash', 21:39:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PeQ01JIJuQ/Listeners', 21:39:21 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 21:39:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:21 INFO - 'USER': 'cltbld', 21:39:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:21 INFO - 'XPC_FLAGS': '0x0', 21:39:21 INFO - 'XPC_SERVICE_NAME': '0', 21:39:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:21 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)) 21:39:21 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)) 21:39:21 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)) 21:39:21 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)) 21:39:21 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)) 21:39:21 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)) 21:39:21 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)) 21:39:21 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)) 21:39:21 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)) 21:39:21 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)) 21:39:21 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)) 21:39:21 INFO - Cleaning up... 21:39:21 INFO - Return code: 0 21:39:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:39:21 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')]} 21:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:21 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')]} 21:39:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:21 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 0x102069490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fdf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb4b584060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e7dd30>, '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 0x101ca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e75b20>, '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.mUfZphFmOo/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.PeQ01JIJuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 21:39:21 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 21:39:21 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 21:39:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mUfZphFmOo/Render', 21:39:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:21 INFO - 'HOME': '/Users/cltbld', 21:39:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:21 INFO - 'LOGNAME': 'cltbld', 21:39:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:21 INFO - 'MOZ_NO_REMOTE': '1', 21:39:21 INFO - 'NO_EM_RESTART': '1', 21:39:21 INFO - 'PAGER': '/bin/cat', 21:39:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:21 INFO - 'PWD': '/builds/slave/test', 21:39:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:21 INFO - 'SHELL': '/bin/bash', 21:39:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PeQ01JIJuQ/Listeners', 21:39:21 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 21:39:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:21 INFO - 'USER': 'cltbld', 21:39:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:21 INFO - 'XPC_FLAGS': '0x0', 21:39:21 INFO - 'XPC_SERVICE_NAME': '0', 21:39:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:39:21 INFO - Cleaning up... 21:39:21 INFO - Return code: 0 21:39:21 INFO - Running post-action listener: _resource_record_post_action 21:39:21 INFO - Running post-action listener: _start_resource_monitoring 21:39:21 INFO - Starting resource monitoring. 21:39:21 INFO - [mozharness: 2016-10-05 04:39:21.596498Z] Finished create-virtualenv step (success) 21:39:21 INFO - [mozharness: 2016-10-05 04:39:21.597510Z] Running install step. 21:39:21 INFO - Running pre-action listener: _resource_record_pre_action 21:39:21 INFO - Running main action method: install 21:39:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:39:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:39:21 INFO - Reading from file tmpfile_stdout 21:39:21 INFO - Detecting whether we're running mozinstall >=1.0... 21:39:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:39:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:39:21 INFO - Reading from file tmpfile_stdout 21:39:21 INFO - Output received: 21:39:21 INFO - Usage: mozinstall [options] installer 21:39:21 INFO - Options: 21:39:21 INFO - -h, --help show this help message and exit 21:39:21 INFO - -d DEST, --destination=DEST 21:39:21 INFO - Directory to install application into. [default: 21:39:21 INFO - "/builds/slave/test"] 21:39:21 INFO - --app=APP Application being installed. [default: firefox] 21:39:21 INFO - mkdir: /builds/slave/test/build/application 21:39:21 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'] 21:39:21 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 21:39:56 INFO - Reading from file tmpfile_stdout 21:39:56 INFO - Output received: 21:39:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:39:56 INFO - Running post-action listener: _resource_record_post_action 21:39:56 INFO - [mozharness: 2016-10-05 04:39:56.084953Z] Finished install step (success) 21:39:56 INFO - [mozharness: 2016-10-05 04:39:56.085096Z] Running run-tests step. 21:39:56 INFO - Running pre-action listener: _resource_record_pre_action 21:39:56 INFO - Running main action method: run_tests 21:39:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:39:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:39:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:39:56 INFO - 2016-10-04 21:39:56.099 screenresolution[1930:15259] starting screenresolution argv=screenresolution get 21:39:56 INFO - 2016-10-04 21:39:56.102 screenresolution[1930:15259] Display 0: 1600x1200x32@60 21:39:56 INFO - 2016-10-04 21:39:56.108 screenresolution[1931:15261] starting screenresolution argv=screenresolution list 21:39:56 INFO - Available Modes on Display 0 21:39:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:39:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:39:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:39:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:39:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:39:56 INFO - Intel Iris: 21:39:56 INFO - Chipset Model: Intel Iris 21:39:56 INFO - Type: GPU 21:39:56 INFO - Bus: Built-In 21:39:56 INFO - VRAM (Dynamic, Max): 1536 MB 21:39:56 INFO - Vendor: Intel (0x8086) 21:39:56 INFO - Device ID: 0x0a2e 21:39:56 INFO - Revision ID: 0x0009 21:39:56 INFO - Displays: 21:39:56 INFO - Display: 21:39:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:39:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:39:56 INFO - Display Serial Number: 1600x1200 60 21:39:56 INFO - Main Display: Yes 21:39:56 INFO - Mirror: Off 21:39:56 INFO - Online: Yes 21:39:56 INFO - Rotation: Supported 21:39:56 INFO - Return code: 0 21:39:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:39:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:39:56 INFO - Python 2.7.3 21:39:56 INFO - Return code: 0 21:39:56 INFO - grabbing minidump binary from tooltool 21:39: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')]} 21:39:56 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 0x101e7dd30>, '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 0x101ca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e75b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:39:56 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 21:39:56 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 21:39:56 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 21:39:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:39:56 INFO - Return code: 0 21:39:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:39:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:39:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:39:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:39:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:39:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0130', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:39:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0130 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:39:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mUfZphFmOo/Render', 21:39:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:56 INFO - 'HOME': '/Users/cltbld', 21:39:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:56 INFO - 'LOGNAME': 'cltbld', 21:39:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:39:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:56 INFO - 'MOZ_NO_REMOTE': '1', 21:39:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:39:56 INFO - 'NO_EM_RESTART': '1', 21:39:56 INFO - 'PAGER': '/bin/cat', 21:39:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:56 INFO - 'PWD': '/builds/slave/test', 21:39:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:39:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:56 INFO - 'SHELL': '/bin/bash', 21:39:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PeQ01JIJuQ/Listeners', 21:39:56 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 21:39:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:56 INFO - 'USER': 'cltbld', 21:39:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:56 INFO - 'XPC_FLAGS': '0x0', 21:39:56 INFO - 'XPC_SERVICE_NAME': '0', 21:39:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0130', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:39:56 INFO - mozversion application_buildid: 20161004195000 21:39:56 INFO - mozversion application_changeset: d3366b063f5231867f2cea79ae229ac4b4e2c63b 21:39:56 INFO - mozversion application_display_name: Nightly 21:39:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:39:56 INFO - mozversion application_name: Firefox 21:39:56 INFO - mozversion application_remotingname: firefox 21:39:56 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:39:56 INFO - mozversion application_vendor: Mozilla 21:39:56 INFO - mozversion application_version: 52.0a1 21:39:56 INFO - mozversion platform_buildid: 20161004195000 21:39:56 INFO - mozversion platform_changeset: d3366b063f5231867f2cea79ae229ac4b4e2c63b 21:39:56 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:39:56 INFO - mozversion platform_version: 52.0a1 21:39:56 INFO - using testdate: 1475642396 21:39:56 INFO - actual date: 1475642396 21:39:56 INFO - starting webserver on 'localhost:51766' 21:39:56 INFO - SUITE-START | Running 2 tests 21:39:56 INFO - TEST-START | dromaeo_css 21:39:56 INFO - operating with platform_type : mac_ 21:39:56 INFO - Initialising browser for dromaeo_css test... 21:39:56 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpBJfxnM/profile http://localhost:51766/getInfo.html) 21:39:59 INFO - PROCESS | 1941 | __metrics Screen width/height:1600/1200 21:39:59 INFO - PROCESS | 1941 | colorDepth:24 21:39:59 INFO - PROCESS | 1941 | Browser inner width/height: 1024/689 21:39:59 INFO - PROCESS | 1941 | __metrics 21:39:59 INFO - PROCESS | 1941 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:39:59 INFO - PROCESS | 1941 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:39:59 INFO - TEST-INFO | 1941: exit 0 21:39:59 INFO - Browser initialized. 21:39:59 INFO - Running cycle 1/1 for dromaeo_css test... 21:39:59 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpBJfxnM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:40:11 INFO - PROCESS | 1943 | RSS: Main: 235954176 21:40:11 INFO - PROCESS | 1943 | 21:41:03 INFO - PROCESS | 1943 | 1475642463120 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] 21:43:16 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51766/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51766/tests/dromaeo/cssquery-ext.html) 21:43:17 INFO - PROCESS | 1943 | RSS: Main: 398974976 21:43:17 INFO - PROCESS | 1943 | 21:46:20 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51766/tests/dromaeo/cssquery-ext.html (next: http://localhost:51766/tests/dromaeo/cssquery-jquery.html) 21:46:21 INFO - PROCESS | 1943 | RSS: Main: 380092416 21:46:21 INFO - PROCESS | 1943 | 21:49:23 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51766/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51766/tests/dromaeo/cssquery-mootools.html) 21:49:24 INFO - PROCESS | 1943 | RSS: Main: 337227776 21:49:24 INFO - PROCESS | 1943 | 21:52:26 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51766/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51766/tests/dromaeo/cssquery-prototype.html) 21:52:27 INFO - PROCESS | 1943 | RSS: Main: 336691200 21:52:27 INFO - PROCESS | 1943 | 21:55:30 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51766/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51766/tests/dromaeo/cssquery-yui.html) 21:55:31 INFO - PROCESS | 1943 | RSS: Main: 338362368 21:55:31 INFO - PROCESS | 1943 | 21:58:34 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51766/tests/dromaeo/cssquery-yui.html (next: http://localhost:51766/tests/dromaeo/cssquery-dojo.html) 21:58:34 INFO - PROCESS | 1943 | RSS: Main: 488468480 21:58:34 INFO - PROCESS | 1943 | 21:58:34 INFO - PROCESS | 1943 | __start_tp_report 21:58:34 INFO - PROCESS | 1943 | _x_x_mozilla_page_load 21:58:34 INFO - PROCESS | 1943 | _x_x_mozilla_page_load_details 21:58:34 INFO - PROCESS | 1943 | |i|pagename|runs| 21:58:34 INFO - PROCESS | 1943 | |0;dojo.html;2760.30992251937;3228.2090887732493;3229.9192520187007;3232.644409114997;3237.28779668473;5589.888202235958;5597.244372009787;5658.179563963223;5678.403767604404;5729.026065568848;5652.604317697753;5657.745401456934;5706.258186435757;5709.029464990951;5754.367019627837;5161.922571161417;5196.896062078757;5202.973985130078;5228.895422091556;5230.006298803234;5155.896882062357;5251.868703282429;5269.209618557228;5276.419593844693;5279.656822306538;5266.078436273656;5287.154055351145;5318.867028324285;5319.069162896497;5352.384475785276;5123.257127716498;5149.665271757362;5222.268882356473;5232.502912223352;5233.790648374061;5109.41241757199;5112.769925353368;5157.71632560209;5164.1995490699;5192.636515443921;5586.441355864422;5592.916106258409;5603.915941260884;5699.2590963174525;5709.942900570983;6179.134921111048;6192.81421557354;6206.596571222901;6208.882401167788;6231.906521402183;5120.206368012964;6131.049687298476;6155.138280640714;6157.107219453154;6215.968920155416;5772.884542309131;5777.24895763548;5783.21926539916;5788.5369170466265;5796.971015144897;6267.373262673787;6279.591826531261;6282.4974002079725;6285.748570057147;6301.180846490019;5904.114382842608;5969.880602388015;5973.49225315853;6015.54883383748;6086.239220097488;6108.297004011545;6155.045967874987;6163.19878415803;6174.166487524217;6200.56596038282;5335.759890804846;5994.014962593516;6175.44421002112;6189.257289125334;6189.876202476015;6207.317195108534;6208.348123446973;6221.129041934133;6232.563720539519;6235.158253635792;4822.348982887335;4880.975595122072;4885.047415753894;4885.877853053702;4897.534734200317;4306.633936115465;4311.353297005474;4362.629176520031;4421.801018954155;4470.485894122153;5680.375158732536;5690.061139911951;5702.8289151325525;5703.9429605704245;5726.455986681259;6137.539684523678;6178.042403427476;6195.19462469876;6278.15244941927;6292.685365731695;4747.406574178228;5622.297212848394;5630.268065151504;5654.15187721845;5748.942510574924;5372.221027950925;5372.355317361864;5374.3013408256675;5375.973120134374;5387.757550910218;6265.342139075423;6287.717052732546;6288.402601752826;6293.402126797947;6297.307296197506;5758.798442054508;6094.664793436404;6147.0779383092895;6168.321484636596;6264.937350626617;5821.854453638693;5918.467337939606;5930.881382372415;5975;5997.580169388276;5717.514011309086;5721.22763426932;5721.885562288814;5727.599068065196;5738.340090889601;5421.40364559906;5730.598858080061;5815.651060936358;5825.79609713658;5916.852078698067;5078.577998160521;5398.1093119634825;5571.13647384656;6040.697965101639;6108;5911.05423132314;6083.270007599116;6088.908666369919;6105.755769769159;6106.755729770759;5729;5921.259842519685;5952.166696662384;5955.3746856581165;5969.9701501493955;5975.760969561343;6018.097285407224;6036.215292012011;6080.027195648675;6097.817065488042;5938.465538101592;5955.553333500092;5957;5966.284045914518;6006.819795406145 21:58:34 INFO - PROCESS | 1943 | |1;ext.html;465.7810828910412;477.5081665884138;535.3655917737482;536.5117742854004;551.2530521143852;30568.54147187791;31179.376412471738;31979.040628781146;41462.963425914364;42346.364804527955;43260.13479730403;43778.7811060945;43822;43896.56103438965;43945.560544394546;9824.656137035186;10022.298439109265;10025.24810639205;10026.74933126674;10063.798724025515;9903.702888913343;10030.548625311878;10030.849537256947;10031.648892288771;10031.996800319946;10016.198704103654;10071.597136114546;10074.949625251864;10077.949610251939;10121.544530496109;9985.950070249639;10018.499075046218;10034.69895903124;10040.598376065022;10054.04486573778;9726.90273097267;9812.607495700235;9813.656522021694;9817.723695919485;9818.116369526777;41444.79277603592;41819.1636167278;41925.58074419247;41954.01436863493;42020.36969445461;3370.2416956184907;3543.0787995121455;3559.7864128152137;3843.7693738375506;4026.4362989181536;30904.690953090405;31276.218094547592;31530.054098377084;31786.523202151988;31973.200669983205;3239.3683231769814;3277.557529733503;3277.6886195811358;3300.851461684206;3306.537084808129;393442;393822;394033;394418;394911.0254448738;62034;62332.68833655803;62569.68715156486;62625.68687156535;62688;34963.65036349655;35263.82368088143;35619.643803562154;36739.26521469556;65019.34980650133;16556.75164872528;16730.6653866922;16741.916290418714;16752.49742507727;16760.245788939526;12713.11008229415;12810.743785124245;12840.229586224856;12849;12849.807252891214;15426.691466170614;15625.06249625026;19008.14463349125;19164.61670766577;19300.1314940828;7939.563324017235;8077.313428358538;8080.151584083704;8095.311898488577;8132.83734325322;902.3818684201212;930.3253147201794;976.8974257702981;1014.8883622801486;1058.8147074261974;30525.778968840998;30687.846560767055;30704.53943190854;31429.528557071662;31495.685043149733;1067.4555976451911;1090.095220966596;1103.1560855945208;1105.690406686129;1138.829175623663;15981;16044.67910641804;16077.678446431006;16105.953113047615;16583.419580314872;677309;682791;682915;683178.1682183115;684359;59589.40410595925;59995;60027;60102.79794404175;60122.39877601255;1333.146786056905;1393.4426229508279;1402.1937386002885;1402.4109873853006;1406.156306216262;27511.724882751718;28334.71665283403;28632.570511442762;28827.84688612432;28994.7100528992;52644.736776315876;53025.20462192993;53165;53191.6702082451;53196.46803531915;571384;574056;575745;575957;577187;26830.731692682824;27044.864775675996;27350.72649273482;27635.269238652523;28691.282717931383;48049;49200.65187614342;49211.507884922125;49323;49806.501934981636;30289.848550757106;37795.62204378031;38022.809885950395;39206.607933921434;39586.40620390639;38256.36564526366;39010.903855095945;39697.40453893252;39731.80134099311;40530.78407647775;31060.68939310578;33500;33748.15629609279;39051.609483904795;39615.40576891407;32407.351852962336;33161.17097072496;35078.65906444965;35495.46756798599;35681.64318356887;25466.618000730374;25616.847241874533;25621.87189064043;26427.94811788499;26813.329666758487 21:58:34 INFO - PROCESS | 1943 | |2;jquery.html;347.5464518802963;374.0256631475008;376.625257868421;384.19702821103897;394.1230761555541;19619;20304.492387690298;21016.579668406623;21244.93775311236;21252.14991400344;10824.945875270612;11868.991135753471;11915.7616847663;12090.33503157326;12104.09219308551;5224.764885580139;5580.079286917674;5580.609357344987;5584.664920104787;5593;3874.147687508758;4105.5894410559;4111.342185250361;5437.537809286216;6465.773697920574;6697.598144111369;6808.21705503866;6813.318668133196;6813.4208592269715;6822.249552549216;5766.625169363999;6085.630733085042;6147.385261473862;6171.722272497748;6182.598131121463;5084.322173682675;5602.0196465618355;5991.730372133265;6004.249468816398;6012.0380739081975;13255.602331930058;13303.135296205812;13509.459621615124;13514.729705405935;13517.188968661912;2106.157536985203;2325.8720770357622;2620.6331113644105;2682.637843891089;2982.567527708492;5520.530754885839;5526.50261476469;5527.419620939783;5548.72256387179;6053.545984051169;1745.6683230186225;1775.5916139288036;1783.2777725021388;1862.966053840118;1888.6694828405111;110147.9999999992;120115;122161;124022.75408393792;128040;9638.084381983701;11285.435728213557;11359.829602555968;11547.113221694577;11589.59436419738;14477.91415643831;14485.92757036208;14493.275336233146;14518.564443066725;14570.05294655844;7800.414968877243;8451.492910425393;8616.009158946676;8638.56807159652;8772.254358379481;7963.601819908981;8477.74566762998;8504.532250726146;8528.829423411496;8708.91291087081;3701.9264413320375;4131.35963925592;4188.874333769931;4437.134758722018;4573.062522182944;1740.8781385303162;1799.6850551153495;1804.128754525013;1906.1898693055396;1906.8855868647925;860.9569521523899;928.6239073175375;935.4995077633433;965.1603105298435;969.3263182088381;17665.646687066444;20626.86552239611;20980.19185249782;21256;21738.565228695337;903.5182301026322;910.0762725833284;959.7792507723262;968.0271327316002;987.5506644476745;9989.700308990741;10800.94599527013;11361.545538178545;11398.487068081957;11446.885531144582;546691;547240;551194;587040;594165;98862.5056874711;116678.41660791812;123765.38117309536;129772.35113824559;130804;861.875028120919;903.8960519540205;934.1265916368218;935.1630290889616;954.1508057828538;16429.753553696464;19160.616787664447;19320.033998299747;20046.89976550166;20184.394468165392;43475;48105.75947120242;50127.74936125296;51053.97892042064;53439;70688.29311707022;73932;74659.62670186615;75059;75931.24068759242;8002.399820013638;8014.8397032057865;8143.959280203561;8235.13531079252;8259.174082591693;11054.392008439612;12679.619611411672;12712.427940742504;13056.151350162208;13241.867581324448;11053.060489858452;11291.322520648459;11391.829122563002;11430.314181149157;11703.2978021319;11171.832422513831;11196.74045365339;11301.773964520828;11601.955823975883;11735.47190376418;8865.601047952729;11068.28056176346;11469.713257168638;11521.769564608494;11707.239029463057;7935.761927142195;8208.138145494637;8231.629576668944;8383.664653413794;8407.663693452194;10524.36853788775;11026.724331891766;11342.886571134182;11624.476898539417;11695.47958765355 21:58:34 INFO - PROCESS | 1943 | |3;mootools.html;1551.62760937375;1553.2544378698224;1792.1576858876324;1914.3873960332699;1949.512621844538;8543.61553730082;9042.54787260636;9063.184313411792;9070.909290907097;9094.72715818525;7953.085395179556;8196.836063278732;8200.343972482187;8520.78698032548;8833.558322083902;2342.121704360865;2387.713474383068;2389.7729715676983;2389.8088152947807;2392.569337519246;1867.3930972433945;2338.6141286687675;2372.893219805113;2377.227401094651;2386.6300723387817;2354.8704821234824;2368.95262094758;2375.8336916415856;2376.3940195250275;2384.6780684607616;2323.1288266899915;2335.0543030072763;2341.9297421077395;2343.0861963834204;2344.15610380263;2346.624540073597;2349.8237632177484;2356.2342238772294;2356.622940329556;2357.4695693468834;8878.644854205824;9086.50024248666;9091.590878410487;9097.681581144629;9105.681301154495;5205.869853253661;5357.919631205535;5366.4096949335935;5370.597205209625;5376.838694839161;3474.810869152443;3538.628444013393;3702;3715.832787524541;3724.6647801697977;1089.2701889733896;1094.7919895219882;1097.0948967102172;1115.4645770030431;1116.1907616977626;11666.475008624633;11792.528298868036;11799.469023893946;11805.291682499077;11815.232009919413;4493.258612328929;4589.678722489461;4750.239961606127;4774.830166609203;4778.116048530964;9961.252906031932;9962.601495940226;9967;9982.401055936667;9991.100800927807;5365.222042803772;5370.570354371641;5376;5379.731013449312;5398;4117.287309605586;4423.60187583119;4800.255960326155;4908.337374454474;4933.901321973541;3859;3859.536855577349;3935.4293627423863;3938.8424463021734;3963.326234540135;1268.5750273658427;1276.4192292506887;1277.8913792327571;1287.1440492072854;1292.6713712191145;832.9380040448476;861.9914699801247;902.5712786426526;904.1094521895927;921.1985572551923;5706.600537962386;5808.506276966421;6055.273367195965;6062;6169.784057557964;941.4257303045136;945.0643862576138;959.2805395953035;964.6575465709712;987.0259481037924;3933.5476420212044;3979.6816254699556;4091.447654566655;4102.097538541516;4104.897377565525;10606.416647084485;11092.334459932268;11100.777984440267;11107.666769996913;11108.833367499494;11027.503762330714;11041.074115359435;11041.227114102096;11051.502682379312;11069.280496767846;7661.348785353307;8105.189481051965;8259.091499935003;8420.747377578682;8430.536320502431;5948;6032.125341825498;6038.818835434944;6102;6107;9843.60625574969;10063.849042264519;10071.798564028824;10115.59537618487;10125.341852779546;9908.207343412365;10226.744331391776;10272.845907311246;10292.382457052601;10423.895761042293;6987.196472405739;7158.06945097134;7170.3149759805365;7460.29127232912;7630;7970.641321140447;8045.758627241192;8060.919390806252;8125.309348705427;8322.6670933162;7414.518056326321;7543.245675432413;7588.354989825927;7635.656395462329;7691.1539730628465;7337.156227033746;7401.740939067106;7404.629768511445;7552.471327007057;7582.582957937367;6946.722131114699;7113.786586402416;7114.537555059112;7115.466340024415;7122.074130363019;4306.41863348444;4928.827491037797;4997.175466048061;5012.824551140692;5112.846614601568;4979.253112033224;4993.80024799004;5031.471695471922;5193.0652482553505;5278.630495865253 21:58:34 INFO - PROCESS | 1943 | |4;prototype.html;304.56143153858443;309.1729623257785;314.61145485325625;318.9856456459459;323.0405695085595;4421.336799480079;5325.121354976424;5326.922500224915;5349.91975120373;5357.276767636368;4989.850304490871;5164.431912489623;5247.86880327992;5263.76313065912;5344.514800617712;3977.144913843523;4023.3964905264297;4033.394990751396;4046.1705350403095;4058.4521089652962;2711.3086163028497;3658.46952191932;3903.5706072332023;4021.879343619696;4034.677225821942;3673.3571624965525;4013.739106958053;4023.5171779386515;4037.7173597848164;4042.2319759245797;3313.1280826333045;4023.2556976959354;4054.837806487737;4058.370952502367;4081.571434999342;3018.894338698157;3079.3533357994847;3348.598168219805;3543.4330507118993;3546.840392182358;4211.642010429117;4572.565606267399;4985.504348695377;5126.512981266774;5150.819721309774;3662.7436079474446;4625.167469855424;4654.930176047362;4656.231721765905;4660.3941487606735;4117.876463706094;4813.638977076734;5159.14874045782;5570.303712035996;5608.467195616451;3920.09837737322;3923.1761330120707;3926.548446928611;3928.8624898128714;3937;5339.092354299778;5497.6085402849885;5750.223719797816;5837.795677151322;5840.101966860787;3555.928881422357;3942.6881419607666;3952.446657467899;3961.870866802947;3976.622220889011;3601.783892966378;3824.7513911595656;4011.4183443400543;4025.9733767889;4033.899152521152;3830.548448992647;4464.374987501752;4669.906601867943;4675.649326300541;4689.6551724137935;5656.660600363992;5687.43125687428;5705.62913410627;5711.543076553866;5777.480026797524;1149.781541507111;1321.841379034522;1340.9195448273135;1345.5357901523957;1345.5492410042746;778.5873487051872;779.7679666127505;816.7345612675904;824.1428913929566;834.5535138700766;3532.4742808891765;3825.713071519647;4292.205941900758;4322.481302243751;4373.584509471595;4008.318585840414;4425.070735145591;4671.645222885393;4783.210770222874;4786.688865223749;3449.534312867731;3789.5263092113487;3830.031725412827;4054.10131755796;4234.433259693933;1930.9131089101024;2266.524029953728;2347.7417484076736;2544.994727082792;2565.320190149612;3887.358585833306;4358;4700.566327270147;4919.311296418504;4939.8885250818275;4161.91676166481;4361.978190109029;4729.62163026957;4808.447028591687;4830.468648448667;4172.4993000840095;4418.459385853147;4629.40285601467;4772.403449568873;4839.879003024882;4571.885702857455;4647.604953578983;4696.040211841033;4795.904081918412;4821.614270858393;4631.282151266559;4700.271457924027;4757.45289291722;4797.984846364466;4879.950420823334;4476.9328460072475;5210.97394513025;5525;5908.434264919713;5914.792982245773;3407.3866703992526;3866.3954458899243;3871.6316804380367;3881.6118388160958;3897.109901697731;4725.905481890274;5517.917231241615;5734.799282025109;5822.7670893163795;5867.266591676041;5310.8141215057285;5571.721413929368;5592.105263157875;5617.494425501724;5653.321601407857;4810.470848206624;5442.673439593637;5571.8885622288135;5639.464250896158;5665.2720920119755;5258.789648414021;5314.681119132864;5463.808766693306;5497.312835895513;5506.5870059744875;5033.421156566895;5580.38615752275;5582.637128586629;5590.748416321194;5675.574331925205;4432.517790037589;4779.709478440732;4984.853483698697;5155.561777248976;5555.9443705695785 21:58:34 INFO - PROCESS | 1943 | |5;yui.html;715.4991505945837;725.8512005038964;778.2995304226196;779.2947382618732;794.7933537280305;15738.291776870039;16115;16141.273642686077;16170;16172.11053392063;14550.708985820278;14554.345054472547;14570.052946558466;14579.854201457982;14711.63220919475;680.5304340005405;703.8486725354048;706.015108923052;853.6244052616829;856.5845564901018;868.6481974800216;875.3259989807876;886.521278509604;887.7913690282811;891.5007595746371;937.3813283233068;937.4890684576887;938.8685584018209;941.2752180820764;942.6041062753626;726.2991213479019;746.7013194722155;904.398574947659;933.8599210118502;933.9206167475772;691.1119211812846;692.4425837201051;692.9896051559231;694.4028135803205;695.5478938689841;15263.160526171056;15292.923535382251;15298.235088245654;15301.770473442908;15317.31072101747;13756.587302380845;13944.51194208198;14023.368948397245;14045.78931316031;14303.570892873126;12603.873961260453;12703.682407939876;12843.036772242025;12881.613551593467;12897.613071607866;4452.599266066037;4526.411566496335;4537.4328208973875;4663.60359369454;4700.7179569225955;110083.44958275238;111350.44324778405;111418.3287250692;112219.99999999919;114719.85280147174;10698;11189.384583847805;11452.94273528627;11466.426678666034;11701.590444334408;22636.320910372717;22645.094196232294;22649.88675056614;22682.206122785625;22691.092356305893;7954.562499062491;7968.0438347398685;8083.6766529338165;8114.188581141839;8229.025033991918;4375.934360984587;4464.218761716752;4472.575105364985;4631.02748422835;4645.001324715203;3484.7038001769624;3598.226381328027;3600.405933021022;3602.1534939289245;3608.350496910581;643.4755674125572;653.6404977262487;660.570629091085;669.88611935971;680.2924958043579;567.1974156568409;612.1643956000116;623.2738859228947;624.6782906803;627.4133685004444;8348.958255208685;9131.406458580172;9168.81662366749;9178.816423671622;9193.816123677489;515.718933181417;516.4293455731438;542.8317221661298;625.7684656677059;629.2794750011303;4238.237117318912;4471.575200355961;4471.8658440246845;4481.350204220435;4492.1310462369665;68578.65710671415;125564;153732;154355.68466473013;155931.1865372788;102197.44506387251;129411;134847.32576337055;136660.08448521668;137545.74359589667;108685.4565727182;115924;117616.23575646372;117858;119141.40429297798;8683.044865064712;8709.216170544682;8760.693375731818;8823.426477278956;8910.153535414125;60311;60411.09383359165;61576;61679.69160154171;64145;76872;84464.57767711367;85755;86666.13333866833;87230;10981.890181098086;11227.438628068727;11304.886951130713;11470.139739519402;12079.51681932713;12360.567380141652;12407.193532420364;12783.23300601967;12869.35653217354;13028.218306901616;11336.8577795988;13651.658708532366;13809;13879.861201387857;14601.85398146005;13520.79718804199;13912.304384780922;14040.929795350958;14190.787138192729;14580.34388452501;13332.40004199794;13557.86442135566;13882.167069975834;14078.707893663915;14324.785128223293;11350.262232954832;11411.429428528707;11461.48423320936;11594.246373985665;11686.006689431493;9698.321117521706;9980;11541.307521548733;11624.593139240085;11664.475098620753 21:58:34 INFO - PROCESS | 1943 | __end_tp_report 21:58:34 INFO - PROCESS | 1943 | __start_cc_report 21:58:34 INFO - PROCESS | 1943 | _x_x_mozilla_cycle_collect,1858 21:58:34 INFO - PROCESS | 1943 | __end_cc_report 21:58:34 INFO - PROCESS | 1943 | __startTimestamp1475643514849__endTimestamp 21:58:34 INFO - PROCESS | 1943 | 21:58:34 INFO - PROCESS | 1943 | ------- Summary: start ------- 21:58:34 INFO - PROCESS | 1943 | Number of tests: 6 21:58:34 INFO - PROCESS | 1943 | 21:58:34 INFO - PROCESS | 1943 | [#0] dojo.html Cycles:165 Average:5642.41 Median:5754.37 stddev:639.70 (11.1%) stddev-sans-first:600.38 21:58:34 INFO - PROCESS | 1943 | Values: 2760.3 3228.2 3229.9 3232.6 3237.3 5589.9 5597.2 5658.2 5678.4 5729.0 5652.6 5657.7 5706.3 5709.0 5754.4 5161.9 5196.9 5203.0 5228.9 5230.0 5155.9 5251.9 5269.2 5276.4 5279.7 5266.1 5287.2 5318.9 5319.1 5352.4 5123.3 5149.7 5222.3 5232.5 5233.8 5109.4 5112.8 5157.7 5164.2 5192.6 5586.4 5592.9 5603.9 5699.3 5709.9 6179.1 6192.8 6206.6 6208.9 6231.9 5120.2 6131.0 6155.1 6157.1 6216.0 5772.9 5777.2 5783.2 5788.5 5797.0 6267.4 6279.6 6282.5 6285.7 6301.2 5904.1 5969.9 5973.5 6015.5 6086.2 6108.3 6155.0 6163.2 6174.2 6200.6 5335.8 5994.0 6175.4 6189.3 6189.9 6207.3 6208.3 6221.1 6232.6 6235.2 4822.3 4881.0 4885.0 4885.9 4897.5 4306.6 4311.4 4362.6 4421.8 4470.5 5680.4 5690.1 5702.8 5703.9 5726.5 6137.5 6178.0 6195.2 6278.2 6292.7 4747.4 5622.3 5630.3 5654.2 5748.9 5372.2 5372.4 5374.3 5376.0 5387.8 6265.3 6287.7 6288.4 6293.4 6297.3 5758.8 6094.7 6147.1 6168.3 6264.9 5821.9 5918.5 5930.9 5975.0 5997.6 5717.5 5721.2 5721.9 5727.6 5738.3 5421.4 5730.6 5815.7 5825.8 5916.9 5078.6 5398.1 5571.1 6040.7 6108.0 5911.1 6083.3 6088.9 6105.8 6106.8 5729.0 5921.3 5952.2 5955.4 5970.0 5975.8 6018.1 6036.2 6080.0 6097.8 5938.5 5955.6 5957.0 5966.3 6006.8 21:58:34 INFO - PROCESS | 1943 | 21:58:34 INFO - PROCESS | 1943 | [#1] ext.html Cycles:180 Average:68365.68 Median:35171.24 stddev:150748.40 (428.6%) stddev-sans-first:151085.09 21:58:34 INFO - PROCESS | 1943 | Values: 465.8 477.5 535.4 536.5 551.3 30568.5 31179.4 31979.0 41463.0 42346.4 43260.1 43778.8 43822.0 43896.6 43945.6 9824.7 10022.3 10025.2 10026.7 10063.8 9903.7 10030.5 10030.8 10031.6 10032.0 10016.2 10071.6 10074.9 10077.9 10121.5 9986.0 10018.5 10034.7 10040.6 10054.0 9726.9 9812.6 9813.7 9817.7 9818.1 41444.8 41819.2 41925.6 41954.0 42020.4 3370.2 3543.1 3559.8 3843.8 4026.4 30904.7 31276.2 31530.1 31786.5 31973.2 3239.4 3277.6 3277.7 3300.9 3306.5 393442.0 393822.0 394033.0 394418.0 394911.0 62034.0 62332.7 62569.7 62625.7 62688.0 34963.7 35263.8 35619.6 36739.3 65019.3 16556.8 16730.7 16741.9 16752.5 16760.2 12713.1 12810.7 12840.2 12849.0 12849.8 15426.7 15625.1 19008.1 19164.6 19300.1 7939.6 8077.3 8080.2 8095.3 8132.8 902.4 930.3 976.9 1014.9 1058.8 30525.8 30687.8 30704.5 31429.5 31495.7 1067.5 1090.1 1103.2 1105.7 1138.8 15981.0 16044.7 16077.7 16106.0 16583.4 677309.0 682791.0 682915.0 683178.2 684359.0 59589.4 59995.0 60027.0 60102.8 60122.4 1333.1 1393.4 1402.2 1402.4 1406.2 27511.7 28334.7 28632.6 28827.8 28994.7 52644.7 53025.2 53165.0 53191.7 53196.5 571384.0 574056.0 575745.0 575957.0 577187.0 26830.7 27044.9 27350.7 27635.3 28691.3 48049.0 49200.7 49211.5 49323.0 49806.5 30289.8 37795.6 38022.8 39206.6 39586.4 38256.4 39010.9 39697.4 39731.8 40530.8 31060.7 33500.0 33748.2 39051.6 39615.4 32407.4 33161.2 35078.7 35495.5 35681.6 25466.6 25616.8 25621.9 26427.9 26813.3 21:58:34 INFO - PROCESS | 1943 | 21:58:34 INFO - PROCESS | 1943 | [#2] jquery.html Cycles:180 Average:33062.19 Median:2129.17 stddev:94673.72 (4446.5%) stddev-sans-first:94907.43 21:58:34 INFO - PROCESS | 1943 | Values: 347.5 374.0 376.6 384.2 394.1 19619.0 20304.5 21016.6 21244.9 21252.1 10824.9 11869.0 11915.8 12090.3 12104.1 5224.8 5580.1 5580.6 5584.7 5593.0 3874.1 4105.6 4111.3 5437.5 6465.8 6697.6 6808.2 6813.3 6813.4 6822.2 5766.6 6085.6 6147.4 6171.7 6182.6 5084.3 5602.0 5991.7 6004.2 6012.0 13255.6 13303.1 13509.5 13514.7 13517.2 2106.2 2325.9 2620.6 2682.6 2982.6 5520.5 5526.5 5527.4 5548.7 6053.5 1745.7 1775.6 1783.3 1863.0 1888.7 110148.0 120115.0 122161.0 124022.8 128040.0 9638.1 11285.4 11359.8 11547.1 11589.6 14477.9 14485.9 14493.3 14518.6 14570.1 7800.4 8451.5 8616.0 8638.6 8772.3 7963.6 8477.7 8504.5 8528.8 8708.9 3701.9 4131.4 4188.9 4437.1 4573.1 1740.9 1799.7 1804.1 1906.2 1906.9 861.0 928.6 935.5 965.2 969.3 17665.6 20626.9 20980.2 21256.0 21738.6 903.5 910.1 959.8 968.0 987.6 9989.7 10800.9 11361.5 11398.5 11446.9 546691.0 547240.0 551194.0 587040.0 594165.0 98862.5 116678.4 123765.4 129772.4 130804.0 861.9 903.9 934.1 935.2 954.2 16429.8 19160.6 19320.0 20046.9 20184.4 43475.0 48105.8 50127.7 51054.0 53439.0 70688.3 73932.0 74659.6 75059.0 75931.2 8002.4 8014.8 8144.0 8235.1 8259.2 11054.4 12679.6 12712.4 13056.2 13241.9 11053.1 11291.3 11391.8 11430.3 11703.3 11171.8 11196.7 11301.8 11602.0 11735.5 8865.6 11068.3 11469.7 11521.8 11707.2 7935.8 8208.1 8231.6 8383.7 8407.7 10524.4 11026.7 11342.9 11624.5 11695.5 21:58:34 INFO - PROCESS | 1943 | 21:58:34 INFO - PROCESS | 1943 | [#3] mootools.html Cycles:180 Average:5760.37 Median:4986.53 stddev:3239.84 (65.0%) stddev-sans-first:3233.49 21:58:34 INFO - PROCESS | 1943 | Values: 1551.6 1553.3 1792.2 1914.4 1949.5 8543.6 9042.5 9063.2 9070.9 9094.7 7953.1 8196.8 8200.3 8520.8 8833.6 2342.1 2387.7 2389.8 2389.8 2392.6 1867.4 2338.6 2372.9 2377.2 2386.6 2354.9 2369.0 2375.8 2376.4 2384.7 2323.1 2335.1 2341.9 2343.1 2344.2 2346.6 2349.8 2356.2 2356.6 2357.5 8878.6 9086.5 9091.6 9097.7 9105.7 5205.9 5357.9 5366.4 5370.6 5376.8 3474.8 3538.6 3702.0 3715.8 3724.7 1089.3 1094.8 1097.1 1115.5 1116.2 11666.5 11792.5 11799.5 11805.3 11815.2 4493.3 4589.7 4750.2 4774.8 4778.1 9961.3 9962.6 9967.0 9982.4 9991.1 5365.2 5370.6 5376.0 5379.7 5398.0 4117.3 4423.6 4800.3 4908.3 4933.9 3859.0 3859.5 3935.4 3938.8 3963.3 1268.6 1276.4 1277.9 1287.1 1292.7 832.9 862.0 902.6 904.1 921.2 5706.6 5808.5 6055.3 6062.0 6169.8 941.4 945.1 959.3 964.7 987.0 3933.5 3979.7 4091.4 4102.1 4104.9 10606.4 11092.3 11100.8 11107.7 11108.8 11027.5 11041.1 11041.2 11051.5 11069.3 7661.3 8105.2 8259.1 8420.7 8430.5 5948.0 6032.1 6038.8 6102.0 6107.0 9843.6 10063.8 10071.8 10115.6 10125.3 9908.2 10226.7 10272.8 10292.4 10423.9 6987.2 7158.1 7170.3 7460.3 7630.0 7970.6 8045.8 8060.9 8125.3 8322.7 7414.5 7543.2 7588.4 7635.7 7691.2 7337.2 7401.7 7404.6 7552.5 7582.6 6946.7 7113.8 7114.5 7115.5 7122.1 4306.4 4928.8 4997.2 5012.8 5112.8 4979.3 4993.8 5031.5 5193.1 5278.6 21:58:34 INFO - PROCESS | 1943 | 21:58:34 INFO - PROCESS | 1943 | [#4] prototype.html Cycles:180 Average:4251.33 Median:4639.44 stddev:1315.61 (28.4%) stddev-sans-first:1285.52 21:58:34 INFO - PROCESS | 1943 | Values: 304.6 309.2 314.6 319.0 323.0 4421.3 5325.1 5326.9 5349.9 5357.3 4989.9 5164.4 5247.9 5263.8 5344.5 3977.1 4023.4 4033.4 4046.2 4058.5 2711.3 3658.5 3903.6 4021.9 4034.7 3673.4 4013.7 4023.5 4037.7 4042.2 3313.1 4023.3 4054.8 4058.4 4081.6 3018.9 3079.4 3348.6 3543.4 3546.8 4211.6 4572.6 4985.5 5126.5 5150.8 3662.7 4625.2 4654.9 4656.2 4660.4 4117.9 4813.6 5159.1 5570.3 5608.5 3920.1 3923.2 3926.5 3928.9 3937.0 5339.1 5497.6 5750.2 5837.8 5840.1 3555.9 3942.7 3952.4 3961.9 3976.6 3601.8 3824.8 4011.4 4026.0 4033.9 3830.5 4464.4 4669.9 4675.6 4689.7 5656.7 5687.4 5705.6 5711.5 5777.5 1149.8 1321.8 1340.9 1345.5 1345.5 778.6 779.8 816.7 824.1 834.6 3532.5 3825.7 4292.2 4322.5 4373.6 4008.3 4425.1 4671.6 4783.2 4786.7 3449.5 3789.5 3830.0 4054.1 4234.4 1930.9 2266.5 2347.7 2545.0 2565.3 3887.4 4358.0 4700.6 4919.3 4939.9 4161.9 4362.0 4729.6 4808.4 4830.5 4172.5 4418.5 4629.4 4772.4 4839.9 4571.9 4647.6 4696.0 4795.9 4821.6 4631.3 4700.3 4757.5 4798.0 4880.0 4476.9 5211.0 5525.0 5908.4 5914.8 3407.4 3866.4 3871.6 3881.6 3897.1 4725.9 5517.9 5734.8 5822.8 5867.3 5310.8 5571.7 5592.1 5617.5 5653.3 4810.5 5442.7 5571.9 5639.5 5665.3 5258.8 5314.7 5463.8 5497.3 5506.6 5033.4 5580.4 5582.6 5590.7 5675.6 4432.5 4779.7 4984.9 5155.6 5555.9 21:58:34 INFO - PROCESS | 1943 | 21:58:34 INFO - PROCESS | 1943 | [#5] yui.html Cycles:180 Average:24378.62 Median:3605.25 stddev:38709.88 (1073.7%) stddev-sans-first:38777.70 21:58:34 INFO - PROCESS | 1943 | Values: 715.5 725.9 778.3 779.3 794.8 15738.3 16115.0 16141.3 16170.0 16172.1 14550.7 14554.3 14570.1 14579.9 14711.6 680.5 703.8 706.0 853.6 856.6 868.6 875.3 886.5 887.8 891.5 937.4 937.5 938.9 941.3 942.6 726.3 746.7 904.4 933.9 933.9 691.1 692.4 693.0 694.4 695.5 15263.2 15292.9 15298.2 15301.8 15317.3 13756.6 13944.5 14023.4 14045.8 14303.6 12603.9 12703.7 12843.0 12881.6 12897.6 4452.6 4526.4 4537.4 4663.6 4700.7 110083.4 111350.4 111418.3 112220.0 114719.9 10698.0 11189.4 11452.9 11466.4 11701.6 22636.3 22645.1 22649.9 22682.2 22691.1 7954.6 7968.0 8083.7 8114.2 8229.0 4375.9 4464.2 4472.6 4631.0 4645.0 3484.7 3598.2 3600.4 3602.2 3608.4 643.5 653.6 660.6 669.9 680.3 567.2 612.2 623.3 624.7 627.4 8349.0 9131.4 9168.8 9178.8 9193.8 515.7 516.4 542.8 625.8 629.3 4238.2 4471.6 4471.9 4481.4 4492.1 68578.7 125564.0 153732.0 154355.7 155931.2 102197.4 129411.0 134847.3 136660.1 137545.7 108685.5 115924.0 117616.2 117858.0 119141.4 8683.0 8709.2 8760.7 8823.4 8910.2 60311.0 60411.1 61576.0 61679.7 64145.0 76872.0 84464.6 85755.0 86666.1 87230.0 10981.9 11227.4 11304.9 11470.1 12079.5 12360.6 12407.2 12783.2 12869.4 13028.2 11336.9 13651.7 13809.0 13879.9 14601.9 13520.8 13912.3 14040.9 14190.8 14580.3 13332.4 13557.9 13882.2 14078.7 14324.8 11350.3 11411.4 11461.5 11594.2 11686.0 9698.3 9980.0 11541.3 11624.6 11664.5 21:58:34 INFO - PROCESS | 1943 | -------- Summary: end -------- 21:58:34 INFO - PROCESS | 1943 | 21:58:35 INFO - TEST-INFO | 1943: exit 0 21:58:35 INFO - TEST-OK | dromaeo_css | took 1118985ms 21:58:35 INFO - TEST-START | kraken 21:58:35 INFO - operating with platform_type : mac_ 21:58:35 INFO - Initialising browser for kraken test... 21:58:35 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpc_j2Sw/profile http://localhost:51766/getInfo.html) 21:58:37 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 21:58:37 INFO - PROCESS | 1946 | colorDepth:24 21:58:37 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 21:58:37 INFO - PROCESS | 1946 | __metrics 21:58:38 INFO - PROCESS | 1946 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:58:38 INFO - PROCESS | 1946 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:58:38 INFO - TEST-INFO | 1946: exit 0 21:58:38 INFO - Browser initialized. 21:58:38 INFO - Running cycle 1/1 for kraken test... 21:58:38 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpc_j2Sw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:58:50 INFO - PROCESS | 1947 | RSS: Main: 235732992 21:58:50 INFO - PROCESS | 1947 | 21:58:53 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51766/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51766/tests/kraken/driver.html?testName=audio-beat-detection) 21:58:54 INFO - PROCESS | 1947 | RSS: Main: 294342656 21:58:54 INFO - PROCESS | 1947 | 21:58:57 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51766/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51766/tests/kraken/driver.html?testName=audio-dft) 21:58:57 INFO - PROCESS | 1947 | RSS: Main: 293183488 21:58:57 INFO - PROCESS | 1947 | 21:59:01 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51766/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51766/tests/kraken/driver.html?testName=audio-fft) 21:59:02 INFO - PROCESS | 1947 | RSS: Main: 383750144 21:59:02 INFO - PROCESS | 1947 | 21:59:04 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51766/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51766/tests/kraken/driver.html?testName=audio-oscillator) 21:59:04 INFO - PROCESS | 1947 | RSS: Main: 285102080 21:59:04 INFO - PROCESS | 1947 | 21:59:06 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51766/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51766/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:59:07 INFO - PROCESS | 1947 | RSS: Main: 290422784 21:59:07 INFO - PROCESS | 1947 | 21:59:10 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51766/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51766/tests/kraken/driver.html?testName=imaging-darkroom) 21:59:10 INFO - PROCESS | 1947 | RSS: Main: 327688192 21:59:10 INFO - PROCESS | 1947 | 21:59:13 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51766/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51766/tests/kraken/driver.html?testName=imaging-desaturate) 21:59:14 INFO - PROCESS | 1947 | RSS: Main: 330100736 21:59:14 INFO - PROCESS | 1947 | 21:59:16 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51766/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51766/tests/kraken/driver.html?testName=json-parse-financial) 21:59:17 INFO - PROCESS | 1947 | RSS: Main: 326606848 21:59:17 INFO - PROCESS | 1947 | 21:59:19 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51766/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51766/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:59:19 INFO - PROCESS | 1947 | RSS: Main: 303812608 21:59:19 INFO - PROCESS | 1947 | 21:59:21 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51766/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51766/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:59:22 INFO - PROCESS | 1947 | RSS: Main: 304173056 21:59:22 INFO - PROCESS | 1947 | 21:59:24 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51766/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51766/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:59:25 INFO - PROCESS | 1947 | RSS: Main: 311586816 21:59:25 INFO - PROCESS | 1947 | 21:59:28 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51766/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51766/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:59:29 INFO - PROCESS | 1947 | RSS: Main: 320548864 21:59:29 INFO - PROCESS | 1947 | 21:59:32 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51766/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51766/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:59:32 INFO - PROCESS | 1947 | RSS: Main: 319893504 21:59:32 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51766/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51766/tests/kraken/driver.html?testName=ai-astar) 21:59:34 INFO - PROCESS | 1947 | RSS: Main: 363384832 21:59:34 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | __start_tp_report 21:59:34 INFO - PROCESS | 1947 | _x_x_mozilla_page_load 21:59:34 INFO - PROCESS | 1947 | _x_x_mozilla_page_load_details 21:59:34 INFO - PROCESS | 1947 | |i|pagename|runs| 21:59:34 INFO - PROCESS | 1947 | |0;ai-astar;113;113;114;117;162;163;114;114;113;113 21:59:34 INFO - PROCESS | 1947 | |1;audio-beat-detection;152;112;114;150;113;113;113;113;164;141 21:59:34 INFO - PROCESS | 1947 | |2;audio-dft;184;182;184;185;182;185;183;185;182;184 21:59:34 INFO - PROCESS | 1947 | |3;audio-fft;84;75;83;114;61;60;60;59;81;58 21:59:34 INFO - PROCESS | 1947 | |4;audio-oscillator;92;70;90;70;68;70;90;68;69;91 21:59:34 INFO - PROCESS | 1947 | |5;imaging-gaussian-blur;86;84;86;84;86;83;89;130;86;83 21:59:34 INFO - PROCESS | 1947 | |6;imaging-darkroom;92;92;92;93;94;92;92;93;93;93 21:59:34 INFO - PROCESS | 1947 | |7;imaging-desaturate;84;86;87;86;86;83;85;84;83;87 21:59:34 INFO - PROCESS | 1947 | |8;json-parse-financial;53;55;53;53;55;56;55;53;76;84 21:59:34 INFO - PROCESS | 1947 | |9;json-stringify-tinderbox;68;83;78;76;105;68;70;70;72;71 21:59:34 INFO - PROCESS | 1947 | |10;stanford-crypto-aes;86;90;86;89;89;84;86;84;90;92 21:59:34 INFO - PROCESS | 1947 | |11;stanford-crypto-ccm;146;147;145;144;134;157;162;159;216;144 21:59:34 INFO - PROCESS | 1947 | |12;stanford-crypto-pbkdf2;173;172;171;167;224;189;172;173;171;169 21:59:34 INFO - PROCESS | 1947 | |13;stanford-crypto-sha256-iterative;68;69;67;82;90;73;68;69;69;70 21:59:34 INFO - PROCESS | 1947 | __end_tp_report 21:59:34 INFO - PROCESS | 1947 | __start_cc_report 21:59:34 INFO - PROCESS | 1947 | _x_x_mozilla_cycle_collect,1949 21:59:34 INFO - PROCESS | 1947 | __end_cc_report 21:59:34 INFO - PROCESS | 1947 | __startTimestamp1475643574699__endTimestamp 21:59:34 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | ------- Summary: start ------- 21:59:34 INFO - PROCESS | 1947 | Number of tests: 14 21:59:34 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | [#0] ai-astar Cycles:10 Average:123.60 Median:114.00 stddev:20.54 (18.0%) stddev-sans-first:21.42 21:59:34 INFO - PROCESS | 1947 | Values: 113.0 113.0 114.0 117.0 162.0 163.0 114.0 114.0 113.0 113.0 21:59:34 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:127.50 stddev:20.75 (16.3%) stddev-sans-first:20.19 21:59:34 INFO - PROCESS | 1947 | Values: 152.0 112.0 114.0 150.0 113.0 113.0 113.0 113.0 164.0 141.0 21:59:34 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.26 (0.7%) stddev-sans-first:1.33 21:59:34 INFO - PROCESS | 1947 | Values: 184.0 182.0 184.0 185.0 182.0 185.0 183.0 185.0 182.0 184.0 21:59:34 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | [#3] audio-fft Cycles:10 Average:73.50 Median:68.00 stddev:17.86 (26.3%) stddev-sans-first:18.53 21:59:34 INFO - PROCESS | 1947 | Values: 84.0 75.0 83.0 114.0 61.0 60.0 60.0 59.0 81.0 58.0 21:59:34 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:80.00 stddev:11.18 (14.0%) stddev-sans-first:10.62 21:59:34 INFO - PROCESS | 1947 | Values: 92.0 70.0 90.0 70.0 68.0 70.0 90.0 68.0 69.0 91.0 21:59:34 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:86.00 stddev:14.28 (16.6%) stddev-sans-first:15.08 21:59:34 INFO - PROCESS | 1947 | Values: 86.0 84.0 86.0 84.0 86.0 83.0 89.0 130.0 86.0 83.0 21:59:34 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | [#6] imaging-darkroom Cycles:10 Average:92.60 Median:93.00 stddev:0.70 (0.8%) stddev-sans-first:0.71 21:59:34 INFO - PROCESS | 1947 | Values: 92.0 92.0 92.0 93.0 94.0 92.0 92.0 93.0 93.0 93.0 21:59:34 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:86.00 stddev:1.52 (1.8%) stddev-sans-first:1.56 21:59:34 INFO - PROCESS | 1947 | Values: 84.0 86.0 87.0 86.0 86.0 83.0 85.0 84.0 83.0 87.0 21:59:34 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | [#8] json-parse-financial Cycles:10 Average:59.30 Median:55.00 stddev:11.13 (20.2%) stddev-sans-first:11.57 21:59:34 INFO - PROCESS | 1947 | Values: 53.0 55.0 53.0 53.0 55.0 56.0 55.0 53.0 76.0 84.0 21:59:34 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | [#9] json-stringify-tinderbox Cycles:10 Average:76.10 Median:71.50 stddev:11.23 (15.7%) stddev-sans-first:11.52 21:59:34 INFO - PROCESS | 1947 | Values: 68.0 83.0 78.0 76.0 105.0 68.0 70.0 70.0 72.0 71.0 21:59:34 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:89.00 stddev:2.76 (3.1%) stddev-sans-first:2.86 21:59:34 INFO - PROCESS | 1947 | Values: 86.0 90.0 86.0 89.0 89.0 84.0 86.0 84.0 90.0 92.0 21:59:34 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | [#11] stanford-crypto-ccm Cycles:10 Average:155.40 Median:152.00 stddev:22.89 (15.1%) stddev-sans-first:24.03 21:59:34 INFO - PROCESS | 1947 | Values: 146.0 147.0 145.0 144.0 134.0 157.0 162.0 159.0 216.0 144.0 21:59:34 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.10 Median:172.50 stddev:17.19 (10.0%) stddev-sans-first:18.13 21:59:34 INFO - PROCESS | 1947 | Values: 173.0 172.0 171.0 167.0 224.0 189.0 172.0 173.0 171.0 169.0 21:59:34 INFO - PROCESS | 1947 | 21:59:34 INFO - PROCESS | 1947 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:69.50 stddev:7.53 (10.8%) stddev-sans-first:7.81 21:59:34 INFO - PROCESS | 1947 | Values: 68.0 69.0 67.0 82.0 90.0 73.0 68.0 69.0 69.0 70.0 21:59:34 INFO - PROCESS | 1947 | -------- Summary: end -------- 21:59:34 INFO - PROCESS | 1947 | 21:59:35 INFO - TEST-INFO | 1947: exit 0 21:59:35 INFO - TEST-OK | kraken | took 60008ms 21:59:35 INFO - SUITE-END | took 1178s 21:59:35 INFO - Completed test suite (00:19:39) 21:59:35 INFO - javascript benchmark 21:59:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2760.30992251937, 3228.2090887732493, 3229.9192520187007, 3232.644409114997, 3237.28779668473, 5589.888202235958, 5597.244372009787, 5658.179563963223, 5678.403767604404, 5729.026065568848, 5652.604317697753, 5657.745401456934, 5706.258186435757, 5709.029464990951, 5754.367019627837, 5161.922571161417, 5196.896062078757, 5202.973985130078, 5228.895422091556, 5230.006298803234, 5155.896882062357, 5251.868703282429, 5269.209618557228, 5276.419593844693, 5279.656822306538, 5266.078436273656, 5287.154055351145, 5318.867028324285, 5319.069162896497, 5352.384475785276, 5123.257127716498, 5149.665271757362, 5222.268882356473, 5232.502912223352, 5233.790648374061, 5109.41241757199, 5112.769925353368, 5157.71632560209, 5164.1995490699, 5192.636515443921, 5586.441355864422, 5592.916106258409, 5603.915941260884, 5699.2590963174525, 5709.942900570983, 6179.134921111048, 6192.81421557354, 6206.596571222901, 6208.882401167788, 6231.906521402183, 5120.206368012964, 6131.049687298476, 6155.138280640714, 6157.107219453154, 6215.968920155416, 5772.884542309131, 5777.24895763548, 5783.21926539916, 5788.5369170466265, 5796.971015144897, 6267.373262673787, 6279.591826531261, 6282.4974002079725, 6285.748570057147, 6301.180846490019, 5904.114382842608, 5969.880602388015, 5973.49225315853, 6015.54883383748, 6086.239220097488, 6108.297004011545, 6155.045967874987, 6163.19878415803, 6174.166487524217, 6200.56596038282, 5335.759890804846, 5994.014962593516, 6175.44421002112, 6189.257289125334, 6189.876202476015, 6207.317195108534, 6208.348123446973, 6221.129041934133, 6232.563720539519, 6235.158253635792, 4822.348982887335, 4880.975595122072, 4885.047415753894, 4885.877853053702, 4897.534734200317, 4306.633936115465, 4311.353297005474, 4362.629176520031, 4421.801018954155, 4470.485894122153, 5680.375158732536, 5690.061139911951, 5702.8289151325525, 5703.9429605704245, 5726.455986681259, 6137.539684523678, 6178.042403427476, 6195.19462469876, 6278.15244941927, 6292.685365731695, 4747.406574178228, 5622.297212848394, 5630.268065151504, 5654.15187721845, 5748.942510574924, 5372.221027950925, 5372.355317361864, 5374.3013408256675, 5375.973120134374, 5387.757550910218, 6265.342139075423, 6287.717052732546, 6288.402601752826, 6293.402126797947, 6297.307296197506, 5758.798442054508, 6094.664793436404, 6147.0779383092895, 6168.321484636596, 6264.937350626617, 5821.854453638693, 5918.467337939606, 5930.881382372415, 5975.0, 5997.580169388276, 5717.514011309086, 5721.22763426932, 5721.885562288814, 5727.599068065196, 5738.340090889601, 5421.40364559906, 5730.598858080061, 5815.651060936358, 5825.79609713658, 5916.852078698067, 5078.577998160521, 5398.1093119634825, 5571.13647384656, 6040.697965101639, 6108.0, 5911.05423132314, 6083.270007599116, 6088.908666369919, 6105.755769769159, 6106.755729770759, 5729.0, 5921.259842519685, 5952.166696662384, 5955.3746856581165, 5969.9701501493955, 5975.760969561343, 6018.097285407224, 6036.215292012011, 6080.027195648675, 6097.817065488042, 5938.465538101592, 5955.553333500092, 5957.0, 5966.284045914518, 6006.819795406145], "value": 5600.264284879581, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.7810828910412, 477.5081665884138, 535.3655917737482, 536.5117742854004, 551.2530521143852, 30568.54147187791, 31179.376412471738, 31979.040628781146, 41462.963425914364, 42346.364804527955, 43260.13479730403, 43778.7811060945, 43822.0, 43896.56103438965, 43945.560544394546, 9824.656137035186, 10022.298439109265, 10025.24810639205, 10026.74933126674, 10063.798724025515, 9903.702888913343, 10030.548625311878, 10030.849537256947, 10031.648892288771, 10031.996800319946, 10016.198704103654, 10071.597136114546, 10074.949625251864, 10077.949610251939, 10121.544530496109, 9985.950070249639, 10018.499075046218, 10034.69895903124, 10040.598376065022, 10054.04486573778, 9726.90273097267, 9812.607495700235, 9813.656522021694, 9817.723695919485, 9818.116369526777, 41444.79277603592, 41819.1636167278, 41925.58074419247, 41954.01436863493, 42020.36969445461, 3370.2416956184907, 3543.0787995121455, 3559.7864128152137, 3843.7693738375506, 4026.4362989181536, 30904.690953090405, 31276.218094547592, 31530.054098377084, 31786.523202151988, 31973.200669983205, 3239.3683231769814, 3277.557529733503, 3277.6886195811358, 3300.851461684206, 3306.537084808129, 393442.0, 393822.0, 394033.0, 394418.0, 394911.0254448738, 62034.0, 62332.68833655803, 62569.68715156486, 62625.68687156535, 62688.0, 34963.65036349655, 35263.82368088143, 35619.643803562154, 36739.26521469556, 65019.34980650133, 16556.75164872528, 16730.6653866922, 16741.916290418714, 16752.49742507727, 16760.245788939526, 12713.11008229415, 12810.743785124245, 12840.229586224856, 12849.0, 12849.807252891214, 15426.691466170614, 15625.06249625026, 19008.14463349125, 19164.61670766577, 19300.1314940828, 7939.563324017235, 8077.313428358538, 8080.151584083704, 8095.311898488577, 8132.83734325322, 902.3818684201212, 930.3253147201794, 976.8974257702981, 1014.8883622801486, 1058.8147074261974, 30525.778968840998, 30687.846560767055, 30704.53943190854, 31429.528557071662, 31495.685043149733, 1067.4555976451911, 1090.095220966596, 1103.1560855945208, 1105.690406686129, 1138.829175623663, 15981.0, 16044.67910641804, 16077.678446431006, 16105.953113047615, 16583.419580314872, 677309.0, 682791.0, 682915.0, 683178.1682183115, 684359.0, 59589.40410595925, 59995.0, 60027.0, 60102.79794404175, 60122.39877601255, 1333.146786056905, 1393.4426229508279, 1402.1937386002885, 1402.4109873853006, 1406.156306216262, 27511.724882751718, 28334.71665283403, 28632.570511442762, 28827.84688612432, 28994.7100528992, 52644.736776315876, 53025.20462192993, 53165.0, 53191.6702082451, 53196.46803531915, 571384.0, 574056.0, 575745.0, 575957.0, 577187.0, 26830.731692682824, 27044.864775675996, 27350.72649273482, 27635.269238652523, 28691.282717931383, 48049.0, 49200.65187614342, 49211.507884922125, 49323.0, 49806.501934981636, 30289.848550757106, 37795.62204378031, 38022.809885950395, 39206.607933921434, 39586.40620390639, 38256.36564526366, 39010.903855095945, 39697.40453893252, 39731.80134099311, 40530.78407647775, 31060.68939310578, 33500.0, 33748.15629609279, 39051.609483904795, 39615.40576891407, 32407.351852962336, 33161.17097072496, 35078.65906444965, 35495.46756798599, 35681.64318356887, 25466.618000730374, 25616.847241874533, 25621.87189064043, 26427.94811788499, 26813.329666758487], "value": 19967.58966768626, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.5464518802963, 374.0256631475008, 376.625257868421, 384.19702821103897, 394.1230761555541, 19619.0, 20304.492387690298, 21016.579668406623, 21244.93775311236, 21252.14991400344, 10824.945875270612, 11868.991135753471, 11915.7616847663, 12090.33503157326, 12104.09219308551, 5224.764885580139, 5580.079286917674, 5580.609357344987, 5584.664920104787, 5593.0, 3874.147687508758, 4105.5894410559, 4111.342185250361, 5437.537809286216, 6465.773697920574, 6697.598144111369, 6808.21705503866, 6813.318668133196, 6813.4208592269715, 6822.249552549216, 5766.625169363999, 6085.630733085042, 6147.385261473862, 6171.722272497748, 6182.598131121463, 5084.322173682675, 5602.0196465618355, 5991.730372133265, 6004.249468816398, 6012.0380739081975, 13255.602331930058, 13303.135296205812, 13509.459621615124, 13514.729705405935, 13517.188968661912, 2106.157536985203, 2325.8720770357622, 2620.6331113644105, 2682.637843891089, 2982.567527708492, 5520.530754885839, 5526.50261476469, 5527.419620939783, 5548.72256387179, 6053.545984051169, 1745.6683230186225, 1775.5916139288036, 1783.2777725021388, 1862.966053840118, 1888.6694828405111, 110147.9999999992, 120115.0, 122161.0, 124022.75408393792, 128040.0, 9638.084381983701, 11285.435728213557, 11359.829602555968, 11547.113221694577, 11589.59436419738, 14477.91415643831, 14485.92757036208, 14493.275336233146, 14518.564443066725, 14570.05294655844, 7800.414968877243, 8451.492910425393, 8616.009158946676, 8638.56807159652, 8772.254358379481, 7963.601819908981, 8477.74566762998, 8504.532250726146, 8528.829423411496, 8708.91291087081, 3701.9264413320375, 4131.35963925592, 4188.874333769931, 4437.134758722018, 4573.062522182944, 1740.8781385303162, 1799.6850551153495, 1804.128754525013, 1906.1898693055396, 1906.8855868647925, 860.9569521523899, 928.6239073175375, 935.4995077633433, 965.1603105298435, 969.3263182088381, 17665.646687066444, 20626.86552239611, 20980.19185249782, 21256.0, 21738.565228695337, 903.5182301026322, 910.0762725833284, 959.7792507723262, 968.0271327316002, 987.5506644476745, 9989.700308990741, 10800.94599527013, 11361.545538178545, 11398.487068081957, 11446.885531144582, 546691.0, 547240.0, 551194.0, 587040.0, 594165.0, 98862.5056874711, 116678.41660791812, 123765.38117309536, 129772.35113824559, 130804.0, 861.875028120919, 903.8960519540205, 934.1265916368218, 935.1630290889616, 954.1508057828538, 16429.753553696464, 19160.616787664447, 19320.033998299747, 20046.89976550166, 20184.394468165392, 43475.0, 48105.75947120242, 50127.74936125296, 51053.97892042064, 53439.0, 70688.29311707022, 73932.0, 74659.62670186615, 75059.0, 75931.24068759242, 8002.399820013638, 8014.8397032057865, 8143.959280203561, 8235.13531079252, 8259.174082591693, 11054.392008439612, 12679.619611411672, 12712.427940742504, 13056.151350162208, 13241.867581324448, 11053.060489858452, 11291.322520648459, 11391.829122563002, 11430.314181149157, 11703.2978021319, 11171.832422513831, 11196.74045365339, 11301.773964520828, 11601.955823975883, 11735.47190376418, 8865.601047952729, 11068.28056176346, 11469.713257168638, 11521.769564608494, 11707.239029463057, 7935.761927142195, 8208.138145494637, 8231.629576668944, 8383.664653413794, 8407.663693452194, 10524.36853788775, 11026.724331891766, 11342.886571134182, 11624.476898539417, 11695.47958765355], "value": 9021.418973223388, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1551.62760937375, 1553.2544378698224, 1792.1576858876324, 1914.3873960332699, 1949.512621844538, 8543.61553730082, 9042.54787260636, 9063.184313411792, 9070.909290907097, 9094.72715818525, 7953.085395179556, 8196.836063278732, 8200.343972482187, 8520.78698032548, 8833.558322083902, 2342.121704360865, 2387.713474383068, 2389.7729715676983, 2389.8088152947807, 2392.569337519246, 1867.3930972433945, 2338.6141286687675, 2372.893219805113, 2377.227401094651, 2386.6300723387817, 2354.8704821234824, 2368.95262094758, 2375.8336916415856, 2376.3940195250275, 2384.6780684607616, 2323.1288266899915, 2335.0543030072763, 2341.9297421077395, 2343.0861963834204, 2344.15610380263, 2346.624540073597, 2349.8237632177484, 2356.2342238772294, 2356.622940329556, 2357.4695693468834, 8878.644854205824, 9086.50024248666, 9091.590878410487, 9097.681581144629, 9105.681301154495, 5205.869853253661, 5357.919631205535, 5366.4096949335935, 5370.597205209625, 5376.838694839161, 3474.810869152443, 3538.628444013393, 3702.0, 3715.832787524541, 3724.6647801697977, 1089.2701889733896, 1094.7919895219882, 1097.0948967102172, 1115.4645770030431, 1116.1907616977626, 11666.475008624633, 11792.528298868036, 11799.469023893946, 11805.291682499077, 11815.232009919413, 4493.258612328929, 4589.678722489461, 4750.239961606127, 4774.830166609203, 4778.116048530964, 9961.252906031932, 9962.601495940226, 9967.0, 9982.401055936667, 9991.100800927807, 5365.222042803772, 5370.570354371641, 5376.0, 5379.731013449312, 5398.0, 4117.287309605586, 4423.60187583119, 4800.255960326155, 4908.337374454474, 4933.901321973541, 3859.0, 3859.536855577349, 3935.4293627423863, 3938.8424463021734, 3963.326234540135, 1268.5750273658427, 1276.4192292506887, 1277.8913792327571, 1287.1440492072854, 1292.6713712191145, 832.9380040448476, 861.9914699801247, 902.5712786426526, 904.1094521895927, 921.1985572551923, 5706.600537962386, 5808.506276966421, 6055.273367195965, 6062.0, 6169.784057557964, 941.4257303045136, 945.0643862576138, 959.2805395953035, 964.6575465709712, 987.0259481037924, 3933.5476420212044, 3979.6816254699556, 4091.447654566655, 4102.097538541516, 4104.897377565525, 10606.416647084485, 11092.334459932268, 11100.777984440267, 11107.666769996913, 11108.833367499494, 11027.503762330714, 11041.074115359435, 11041.227114102096, 11051.502682379312, 11069.280496767846, 7661.348785353307, 8105.189481051965, 8259.091499935003, 8420.747377578682, 8430.536320502431, 5948.0, 6032.125341825498, 6038.818835434944, 6102.0, 6107.0, 9843.60625574969, 10063.849042264519, 10071.798564028824, 10115.59537618487, 10125.341852779546, 9908.207343412365, 10226.744331391776, 10272.845907311246, 10292.382457052601, 10423.895761042293, 6987.196472405739, 7158.06945097134, 7170.3149759805365, 7460.29127232912, 7630.0, 7970.641321140447, 8045.758627241192, 8060.919390806252, 8125.309348705427, 8322.6670933162, 7414.518056326321, 7543.245675432413, 7588.354989825927, 7635.656395462329, 7691.1539730628465, 7337.156227033746, 7401.740939067106, 7404.629768511445, 7552.471327007057, 7582.582957937367, 6946.722131114699, 7113.786586402416, 7114.537555059112, 7115.466340024415, 7122.074130363019, 4306.41863348444, 4928.827491037797, 4997.175466048061, 5012.824551140692, 5112.846614601568, 4979.253112033224, 4993.80024799004, 5031.471695471922, 5193.0652482553505, 5278.630495865253], "value": 4629.103205916933, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.56143153858443, 309.1729623257785, 314.61145485325625, 318.9856456459459, 323.0405695085595, 4421.336799480079, 5325.121354976424, 5326.922500224915, 5349.91975120373, 5357.276767636368, 4989.850304490871, 5164.431912489623, 5247.86880327992, 5263.76313065912, 5344.514800617712, 3977.144913843523, 4023.3964905264297, 4033.394990751396, 4046.1705350403095, 4058.4521089652962, 2711.3086163028497, 3658.46952191932, 3903.5706072332023, 4021.879343619696, 4034.677225821942, 3673.3571624965525, 4013.739106958053, 4023.5171779386515, 4037.7173597848164, 4042.2319759245797, 3313.1280826333045, 4023.2556976959354, 4054.837806487737, 4058.370952502367, 4081.571434999342, 3018.894338698157, 3079.3533357994847, 3348.598168219805, 3543.4330507118993, 3546.840392182358, 4211.642010429117, 4572.565606267399, 4985.504348695377, 5126.512981266774, 5150.819721309774, 3662.7436079474446, 4625.167469855424, 4654.930176047362, 4656.231721765905, 4660.3941487606735, 4117.876463706094, 4813.638977076734, 5159.14874045782, 5570.303712035996, 5608.467195616451, 3920.09837737322, 3923.1761330120707, 3926.548446928611, 3928.8624898128714, 3937.0, 5339.092354299778, 5497.6085402849885, 5750.223719797816, 5837.795677151322, 5840.101966860787, 3555.928881422357, 3942.6881419607666, 3952.446657467899, 3961.870866802947, 3976.622220889011, 3601.783892966378, 3824.7513911595656, 4011.4183443400543, 4025.9733767889, 4033.899152521152, 3830.548448992647, 4464.374987501752, 4669.906601867943, 4675.649326300541, 4689.6551724137935, 5656.660600363992, 5687.43125687428, 5705.62913410627, 5711.543076553866, 5777.480026797524, 1149.781541507111, 1321.841379034522, 1340.9195448273135, 1345.5357901523957, 1345.5492410042746, 778.5873487051872, 779.7679666127505, 816.7345612675904, 824.1428913929566, 834.5535138700766, 3532.4742808891765, 3825.713071519647, 4292.205941900758, 4322.481302243751, 4373.584509471595, 4008.318585840414, 4425.070735145591, 4671.645222885393, 4783.210770222874, 4786.688865223749, 3449.534312867731, 3789.5263092113487, 3830.031725412827, 4054.10131755796, 4234.433259693933, 1930.9131089101024, 2266.524029953728, 2347.7417484076736, 2544.994727082792, 2565.320190149612, 3887.358585833306, 4358.0, 4700.566327270147, 4919.311296418504, 4939.8885250818275, 4161.91676166481, 4361.978190109029, 4729.62163026957, 4808.447028591687, 4830.468648448667, 4172.4993000840095, 4418.459385853147, 4629.40285601467, 4772.403449568873, 4839.879003024882, 4571.885702857455, 4647.604953578983, 4696.040211841033, 4795.904081918412, 4821.614270858393, 4631.282151266559, 4700.271457924027, 4757.45289291722, 4797.984846364466, 4879.950420823334, 4476.9328460072475, 5210.97394513025, 5525.0, 5908.434264919713, 5914.792982245773, 3407.3866703992526, 3866.3954458899243, 3871.6316804380367, 3881.6118388160958, 3897.109901697731, 4725.905481890274, 5517.917231241615, 5734.799282025109, 5822.7670893163795, 5867.266591676041, 5310.8141215057285, 5571.721413929368, 5592.105263157875, 5617.494425501724, 5653.321601407857, 4810.470848206624, 5442.673439593637, 5571.8885622288135, 5639.464250896158, 5665.2720920119755, 5258.789648414021, 5314.681119132864, 5463.808766693306, 5497.312835895513, 5506.5870059744875, 5033.421156566895, 5580.38615752275, 5582.637128586629, 5590.748416321194, 5675.574331925205, 4432.517790037589, 4779.709478440732, 4984.853483698697, 5155.561777248976, 5555.9443705695785], "value": 3842.4907251762343, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.4991505945837, 725.8512005038964, 778.2995304226196, 779.2947382618732, 794.7933537280305, 15738.291776870039, 16115.0, 16141.273642686077, 16170.0, 16172.11053392063, 14550.708985820278, 14554.345054472547, 14570.052946558466, 14579.854201457982, 14711.63220919475, 680.5304340005405, 703.8486725354048, 706.015108923052, 853.6244052616829, 856.5845564901018, 868.6481974800216, 875.3259989807876, 886.521278509604, 887.7913690282811, 891.5007595746371, 937.3813283233068, 937.4890684576887, 938.8685584018209, 941.2752180820764, 942.6041062753626, 726.2991213479019, 746.7013194722155, 904.398574947659, 933.8599210118502, 933.9206167475772, 691.1119211812846, 692.4425837201051, 692.9896051559231, 694.4028135803205, 695.5478938689841, 15263.160526171056, 15292.923535382251, 15298.235088245654, 15301.770473442908, 15317.31072101747, 13756.587302380845, 13944.51194208198, 14023.368948397245, 14045.78931316031, 14303.570892873126, 12603.873961260453, 12703.682407939876, 12843.036772242025, 12881.613551593467, 12897.613071607866, 4452.599266066037, 4526.411566496335, 4537.4328208973875, 4663.60359369454, 4700.7179569225955, 110083.44958275238, 111350.44324778405, 111418.3287250692, 112219.99999999919, 114719.85280147174, 10698.0, 11189.384583847805, 11452.94273528627, 11466.426678666034, 11701.590444334408, 22636.320910372717, 22645.094196232294, 22649.88675056614, 22682.206122785625, 22691.092356305893, 7954.562499062491, 7968.0438347398685, 8083.6766529338165, 8114.188581141839, 8229.025033991918, 4375.934360984587, 4464.218761716752, 4472.575105364985, 4631.02748422835, 4645.001324715203, 3484.7038001769624, 3598.226381328027, 3600.405933021022, 3602.1534939289245, 3608.350496910581, 643.4755674125572, 653.6404977262487, 660.570629091085, 669.88611935971, 680.2924958043579, 567.1974156568409, 612.1643956000116, 623.2738859228947, 624.6782906803, 627.4133685004444, 8348.958255208685, 9131.406458580172, 9168.81662366749, 9178.816423671622, 9193.816123677489, 515.718933181417, 516.4293455731438, 542.8317221661298, 625.7684656677059, 629.2794750011303, 4238.237117318912, 4471.575200355961, 4471.8658440246845, 4481.350204220435, 4492.1310462369665, 68578.65710671415, 125564.0, 153732.0, 154355.68466473013, 155931.1865372788, 102197.44506387251, 129411.0, 134847.32576337055, 136660.08448521668, 137545.74359589667, 108685.4565727182, 115924.0, 117616.23575646372, 117858.0, 119141.40429297798, 8683.044865064712, 8709.216170544682, 8760.693375731818, 8823.426477278956, 8910.153535414125, 60311.0, 60411.09383359165, 61576.0, 61679.69160154171, 64145.0, 76872.0, 84464.57767711367, 85755.0, 86666.13333866833, 87230.0, 10981.890181098086, 11227.438628068727, 11304.886951130713, 11470.139739519402, 12079.51681932713, 12360.567380141652, 12407.193532420364, 12783.23300601967, 12869.35653217354, 13028.218306901616, 11336.8577795988, 13651.658708532366, 13809.0, 13879.861201387857, 14601.85398146005, 13520.79718804199, 13912.304384780922, 14040.929795350958, 14190.787138192729, 14580.34388452501, 13332.40004199794, 13557.86442135566, 13882.167069975834, 14078.707893663915, 14324.785128223293, 11350.262232954832, 11411.429428528707, 11461.48423320936, 11594.246373985665, 11686.006689431493, 9698.321117521706, 9980.0, 11541.307521548733, 11624.593139240085, 11664.475098620753], "value": 7783.0118043820885, "unit": "score"}], "name": "dromaeo_css", "value": 7203.0946005411915, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 117.0, 162.0, 163.0, 114.0, 114.0, 113.0, 113.0], "value": 123.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 112.0, 114.0, 150.0, 113.0, 113.0, 113.0, 113.0, 164.0, 141.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 184.0, 185.0, 182.0, 185.0, 183.0, 185.0, 182.0, 184.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 75.0, 83.0, 114.0, 61.0, 60.0, 60.0, 59.0, 81.0, 58.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 70.0, 90.0, 70.0, 68.0, 70.0, 90.0, 68.0, 69.0, 91.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 84.0, 86.0, 83.0, 89.0, 130.0, 86.0, 83.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 93.0, 94.0, 92.0, 92.0, 93.0, 93.0, 93.0], "value": 92.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 87.0, 86.0, 86.0, 83.0, 85.0, 84.0, 83.0, 87.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 53.0, 53.0, 55.0, 56.0, 55.0, 53.0, 76.0, 84.0], "value": 59.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 83.0, 78.0, 76.0, 105.0, 68.0, 70.0, 70.0, 72.0, 71.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 90.0, 86.0, 89.0, 89.0, 84.0, 86.0, 84.0, 90.0, 92.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 145.0, 144.0, 134.0, 157.0, 162.0, 159.0, 216.0, 144.0], "value": 155.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 172.0, 171.0, 167.0, 224.0, 189.0, 172.0, 173.0, 171.0, 169.0], "value": 178.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 82.0, 90.0, 73.0, 68.0, 69.0, 69.0, 70.0], "value": 72.5, "unit": "score"}], "name": "kraken", "value": 1483.4, "alertThreshold": 2.0}]} 21:59:35 INFO - Return code: 0 21:59:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:59:35 INFO - # TBPL SUCCESS # 21:59:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:59:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:59:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:59:36 INFO - 2016-10-04 21:59:36.012 screenresolution[1951:17370] starting screenresolution argv=screenresolution get 21:59:36 INFO - 2016-10-04 21:59:36.016 screenresolution[1951:17370] Display 0: 1600x1200x32@60 21:59:36 INFO - 2016-10-04 21:59:36.022 screenresolution[1952:17373] starting screenresolution argv=screenresolution list 21:59:36 INFO - Available Modes on Display 0 21:59:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:59:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:59:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:59:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:59:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:59:36 INFO - Intel Iris: 21:59:36 INFO - Chipset Model: Intel Iris 21:59:36 INFO - Type: GPU 21:59:36 INFO - Bus: Built-In 21:59:36 INFO - VRAM (Dynamic, Max): 1536 MB 21:59:36 INFO - Vendor: Intel (0x8086) 21:59:36 INFO - Device ID: 0x0a2e 21:59:36 INFO - Revision ID: 0x0009 21:59:36 INFO - Displays: 21:59:36 INFO - Display: 21:59:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:59:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:59:36 INFO - Display Serial Number: 1600x1200 60 21:59:36 INFO - Main Display: Yes 21:59:36 INFO - Mirror: Off 21:59:36 INFO - Online: Yes 21:59:36 INFO - Rotation: Supported 21:59:36 INFO - Return code: 0 21:59:36 INFO - Running post-action listener: _resource_record_post_action 21:59:36 INFO - [mozharness: 2016-10-05 04:59:36.265204Z] Finished run-tests step (success) 21:59:36 INFO - Running post-run listener: _resource_record_post_run 21:59:36 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 634880; Write bytes: 705757184; Read time: 7; Write time: 2239 21:59:36 INFO - TinderboxPrint: CPU usage
53.7% 21:59:36 INFO - TinderboxPrint: I/O read bytes / time
634,880 / 7 21:59:36 INFO - TinderboxPrint: I/O write bytes / time
705,757,184 / 2,239 21:59:36 INFO - TinderboxPrint: CPU idle
2,249.4 (46.3%) 21:59:36 INFO - TinderboxPrint: CPU system
970.3 (20.0%) 21:59:36 INFO - TinderboxPrint: CPU user
1,638.0 (33.7%) 21:59:36 INFO - TinderboxPrint: Swap in / out
407,810,048 / 0 21:59:36 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399983104; Write bytes: 394668032; Read time: 29490; Write time: 761 21:59:36 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 634880; Write bytes: 294250496; Read time: 7; Write time: 1436 21:59:36 INFO - Running post-run listener: _upload_blobber_files 21:59:36 INFO - Blob upload gear active. 21:59:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:59:36 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 21:59:36 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:59:36 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:59:37 INFO - (blobuploader) - INFO - Open directory for files ... 21:59:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:59:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:59:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:59:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:59:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:59:38 INFO - (blobuploader) - INFO - Done attempting. 21:59:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:59:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:59:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:59:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:59:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:59:39 INFO - (blobuploader) - INFO - Done attempting. 21:59:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:59:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:59:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:59:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:59:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:59:42 INFO - (blobuploader) - INFO - Done attempting. 21:59:42 INFO - (blobuploader) - INFO - Iteration through files over. 21:59:42 INFO - Return code: 0 21:59:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:59:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:59:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d9dd331fb8e1326dda4d0efe18f1e6b2bb991b44ddd29dd7c03564f6ce605474e15c5a7d99f0be7143a7e6b37fba631a718cf2868e6158dbd5b8e2beb60d16b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/642d23d7e15ce57c79b76d40f553c90e901a803f87c329bb25bcdc8a6b8a48e7d62708ea87365280b48b1dd514617f227f2a1d438d93ddff589dc6fd16451b1f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b958f03327437295c0a30f108afb30d4e1e49bc313809b1eb83b83c454e5073414e53c9f8b6d3bab40b04b779d9c7e52891d237fc4e4ad41e0e1fc79e5c24b4"} 21:59:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:59:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:59:42 INFO - Contents: 21:59:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d9dd331fb8e1326dda4d0efe18f1e6b2bb991b44ddd29dd7c03564f6ce605474e15c5a7d99f0be7143a7e6b37fba631a718cf2868e6158dbd5b8e2beb60d16b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/642d23d7e15ce57c79b76d40f553c90e901a803f87c329bb25bcdc8a6b8a48e7d62708ea87365280b48b1dd514617f227f2a1d438d93ddff589dc6fd16451b1f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b958f03327437295c0a30f108afb30d4e1e49bc313809b1eb83b83c454e5073414e53c9f8b6d3bab40b04b779d9c7e52891d237fc4e4ad41e0e1fc79e5c24b4"} 21:59:42 INFO - Running post-run listener: copy_logs_to_upload_dir 21:59:42 INFO - Copying logs to upload dir... 21:59:42 INFO - mkdir: /builds/slave/test/build/upload/logs 21:59:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.374356 ========= master_lag: 0.14 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-04 21:59:42.494914) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 21:59:42.499913) ========= 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.mUfZphFmOo/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.PeQ01JIJuQ/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d9dd331fb8e1326dda4d0efe18f1e6b2bb991b44ddd29dd7c03564f6ce605474e15c5a7d99f0be7143a7e6b37fba631a718cf2868e6158dbd5b8e2beb60d16b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/642d23d7e15ce57c79b76d40f553c90e901a803f87c329bb25bcdc8a6b8a48e7d62708ea87365280b48b1dd514617f227f2a1d438d93ddff589dc6fd16451b1f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b958f03327437295c0a30f108afb30d4e1e49bc313809b1eb83b83c454e5073414e53c9f8b6d3bab40b04b779d9c7e52891d237fc4e4ad41e0e1fc79e5c24b4"} build_url:https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011264 build_url: 'https://queue.taskcluster.net/v1/task/aSDF6x4ARz6QgsGmxcIn1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d9dd331fb8e1326dda4d0efe18f1e6b2bb991b44ddd29dd7c03564f6ce605474e15c5a7d99f0be7143a7e6b37fba631a718cf2868e6158dbd5b8e2beb60d16b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/642d23d7e15ce57c79b76d40f553c90e901a803f87c329bb25bcdc8a6b8a48e7d62708ea87365280b48b1dd514617f227f2a1d438d93ddff589dc6fd16451b1f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8b958f03327437295c0a30f108afb30d4e1e49bc313809b1eb83b83c454e5073414e53c9f8b6d3bab40b04b779d9c7e52891d237fc4e4ad41e0e1fc79e5c24b4"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 21:59:42.575328) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 21:59:42.578673) ========= 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.mUfZphFmOo/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.PeQ01JIJuQ/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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.005111 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 21:59:42.603838) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 21:59:42.604173) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 21:59:42.607563) ========= ========= Total master_lag: 0.31 =========