builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0037 starttime: 1475508335.15 results: success (0) buildid: 20161003072255 builduid: ad960ea5009e49d8a5ed8406be18451c revision: 7aeb526759fe3a57a87f0af96a35d64b4f23f4ab ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 08:25:35.153211) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 08:25:35.153754) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 08:25:35.154124) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 08:25:35.189470) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 08:25:35.189777) ========= 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.GLltipmYjo/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.eTniLqILqn/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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-03 08:25:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.4M=0.001s 2016-10-03 08:25:35 (16.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.620427 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 08:25:35.832964) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 08:25:35.833282) ========= 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.GLltipmYjo/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.eTniLqILqn/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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.028707 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 08:25:35.881757) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-03 08:25:35.882092) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 7aeb526759fe3a57a87f0af96a35d64b4f23f4ab --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 7aeb526759fe3a57a87f0af96a35d64b4f23f4ab --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GLltipmYjo/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.eTniLqILqn/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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-03 08:25:35,955 truncating revision to first 12 chars 2016-10-03 08:25:35,955 Setting DEBUG logging. 2016-10-03 08:25:35,955 attempt 1/10 2016-10-03 08:25:35,955 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/7aeb526759fe?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-03 08:25:37,041 unpacking tar archive at: fx-team-7aeb526759fe/testing/mozharness/ program finished with exit code 0 elapsedTime=1.334891 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-03 08:25:37.239477) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 08:25:37.239843) ========= script_repo_revision: 7aeb526759fe3a57a87f0af96a35d64b4f23f4ab ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 08:25:37.240285) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 08:25:37.240588) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 08:25:37.258528) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-03 08:25:37.258858) ========= /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.GLltipmYjo/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.eTniLqILqn/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 08:25:37 INFO - MultiFileLogger online at 20161003 08:25:37 in /builds/slave/test 08:25:37 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 08:25:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:25:37 INFO - {'append_to_log': False, 08:25:37 INFO - 'base_work_dir': '/builds/slave/test', 08:25:37 INFO - 'blob_upload_branch': 'Fx-Team', 08:25:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:25:37 INFO - 'branch': 'Fx-Team', 08:25:37 INFO - 'buildbot_json_path': 'buildprops.json', 08:25:37 INFO - 'config_files': ('talos/mac_config.py',), 08:25:37 INFO - 'default_actions': ('clobber', 08:25:37 INFO - 'read-buildbot-config', 08:25:37 INFO - 'download-and-extract', 08:25:37 INFO - 'populate-webroot', 08:25:37 INFO - 'create-virtualenv', 08:25:37 INFO - 'install', 08:25:37 INFO - 'run-tests'), 08:25:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:25:37 INFO - 'download_minidump_stackwalk': True, 08:25:37 INFO - 'download_symbols': 'ondemand', 08:25:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:25:37 INFO - 'tooltool.py': '/tools/tooltool.py', 08:25:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:25:37 INFO - '/tools/misc-python/virtualenv.py')}, 08:25:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:25:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:25:37 INFO - 'installer_path': 'installer.exe', 08:25:37 INFO - 'log_level': 'info', 08:25:37 INFO - 'log_name': 'talos', 08:25:37 INFO - 'log_to_console': True, 08:25:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:25:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:25:37 INFO - 'opt_config_files': (), 08:25:37 INFO - 'pip_index': False, 08:25:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:25:37 INFO - 'cmd': ('bash', 08:25:37 INFO - '-c', 08:25:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:25:37 INFO - 'enabled': True, 08:25:37 INFO - 'halt_on_failure': False, 08:25:37 INFO - 'name': 'check_screen_resolution'},), 08:25:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:25:37 INFO - 'cmd': ('bash', 08:25:37 INFO - '-c', 08:25:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:25:37 INFO - 'enabled': True, 08:25:37 INFO - 'halt_on_failure': False, 08:25:37 INFO - 'name': 'check_screen_resolution'},), 08:25:37 INFO - 'run_cmd_checks_enabled': True, 08:25:37 INFO - 'sps_profile': False, 08:25:37 INFO - 'sps_profile_interval': 0, 08:25:37 INFO - 'suite': 'dromaeojs', 08:25:37 INFO - 'system_bits': '32', 08:25:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:25:37 INFO - 'title': 't-yosemite-r7-0037', 08:25:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:25:37 INFO - 'use_talos_json': True, 08:25:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:25:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:25:37 INFO - 'work_dir': 'build'} 08:25:37 INFO - [mozharness: 2016-10-03 15:25:37.469044Z] Running clobber step. 08:25:37 INFO - Running pre-action listener: _resource_record_pre_action 08:25:37 INFO - Running main action method: clobber 08:25:37 INFO - rmtree: /builds/slave/test/build 08:25:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:25:40 INFO - Running post-action listener: _resource_record_post_action 08:25:40 INFO - [mozharness: 2016-10-03 15:25:40.156902Z] Finished clobber step (success) 08:25:40 INFO - [mozharness: 2016-10-03 15:25:40.157139Z] Running read-buildbot-config step. 08:25:40 INFO - Running pre-action listener: _resource_record_pre_action 08:25:40 INFO - Running main action method: read_buildbot_config 08:25:40 INFO - Using buildbot properties: 08:25:40 INFO - { 08:25:40 INFO - "project": "", 08:25:40 INFO - "product": "firefox", 08:25:40 INFO - "script_repo_revision": "production", 08:25:40 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:25:40 INFO - "repository": "", 08:25:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:25:40 INFO - "buildid": "20161003072255", 08:25:40 INFO - "pgo_build": "False", 08:25:40 INFO - "basedir": "/builds/slave/test", 08:25:40 INFO - "buildnumber": 4, 08:25:40 INFO - "slavename": "t-yosemite-r7-0037", 08:25:40 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:25:40 INFO - "platform": "yosemite_r7", 08:25:40 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:25:40 INFO - "branch": "fx-team", 08:25:40 INFO - "repo_path": "integration/fx-team", 08:25:40 INFO - "revision": "7aeb526759fe3a57a87f0af96a35d64b4f23f4ab", 08:25:40 INFO - "stage_platform": "macosx64", 08:25:40 INFO - "builduid": "ad960ea5009e49d8a5ed8406be18451c", 08:25:40 INFO - "slavebuilddir": "test" 08:25:40 INFO - } 08:25:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:25:40 INFO - Running post-action listener: _resource_record_post_action 08:25:40 INFO - [mozharness: 2016-10-03 15:25:40.162720Z] Finished read-buildbot-config step (success) 08:25:40 INFO - [mozharness: 2016-10-03 15:25:40.162935Z] Running download-and-extract step. 08:25:40 INFO - Running pre-action listener: _resource_record_pre_action 08:25:40 INFO - Running main action method: download_and_extract 08:25:40 INFO - mkdir: /builds/slave/test/build/tests 08:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:40 INFO - trying https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:25:40 INFO - Downloading https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/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 08:25:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/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 08:25:41 INFO - Downloaded 1199 bytes. 08:25:41 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:25:41 INFO - Using the following test package requirements: 08:25:41 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:25:41 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:25:41 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:25:41 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:25:41 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:25:41 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:25:41 INFO - u'jsshell-mac.zip'], 08:25:41 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:25:41 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:25:41 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:25:41 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:25:41 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:25:41 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:25:41 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:25:41 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:25:41 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:25:41 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:25:41 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:25:41 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:25:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:25:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:25:41 INFO - Fetch https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:25:42 INFO - Http code: 200 08:25:42 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 08:25:42 INFO - x-amz-cf-id: Y7QjAnBpFrgA6v9DiXiIRuX_KkfZ7kG4Qi13u5zjvJbi6nRFxjDA4Q== 08:25:42 INFO - x-amz-version-id: YXoTGfjx.xZOrm7evlqw7MwDkvj5T9hi 08:25:42 INFO - Expected file size: 16475679 08:25:42 INFO - Obtained file size: 16475679 08:25:49 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 08:25:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:25:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:25:49 INFO - Fetch https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:25:49 INFO - Http code: 200 08:25:49 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 08:25:49 INFO - x-amz-cf-id: LQW7wsH0jtCCowbdjEeS1wuuspMZau_XUbe6M9E6WAZQg3sI90baTA== 08:25:49 INFO - x-amz-version-id: YXoTGfjx.xZOrm7evlqw7MwDkvj5T9hi 08:25:50 INFO - Expected file size: 16475679 08:25:50 INFO - Obtained file size: 16475679 08:25:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:25:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:25:56 INFO - Fetch https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:25:57 INFO - Http code: 200 08:25:57 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 08:25:57 INFO - x-amz-cf-id: x-9xIDjpJEdHTNinFRse3ILCsE05EOM3JSdNgYqTqLxkd8E1kGjGbw== 08:25:57 INFO - x-amz-version-id: TE0vEcbPDl3fxzr4.Tr6g52jOrY_s8lp 08:25:57 INFO - Expected file size: 14408522 08:25:57 INFO - Obtained file size: 14408522 08:25:57 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')]} 08:25:57 INFO - trying https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:25:57 INFO - Downloading https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:25:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/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 08:26:00 INFO - Downloaded 111806904 bytes. 08:26:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:26:00 INFO - mkdir: /builds/slave/test/properties 08:26:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:26:00 INFO - Writing to file /builds/slave/test/properties/build_url 08:26:00 INFO - Contents: 08:26:00 INFO - build_url:https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:26:01 INFO - Running post-action listener: _resource_record_post_action 08:26:01 INFO - Running post-action listener: set_extra_try_arguments 08:26:01 INFO - [mozharness: 2016-10-03 15:26:01.306292Z] Finished download-and-extract step (success) 08:26:01 INFO - [mozharness: 2016-10-03 15:26:01.306440Z] Running populate-webroot step. 08:26:01 INFO - Running pre-action listener: _resource_record_pre_action 08:26:01 INFO - Running main action method: populate_webroot 08:26:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:26:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:26:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:26:01 INFO - '--tppagecycles', 08:26:01 INFO - '7'], 08:26:01 INFO - 'tests': ['tsvgm']}}, 08:26:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:26:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:26:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:26:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:26:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:26:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:26:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:01 INFO - 'talos_options': ['--disable-e10s'], 08:26:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:26:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:26:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:01 INFO - 'talos_options': ['--disable-e10s'], 08:26:01 INFO - 'tests': ['damp', 'tps']}, 08:26:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:01 INFO - 'tests': ['damp', 'tps']}, 08:26:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:26:01 INFO - 'tests': ['dromaeo_dom']}, 08:26:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:26:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:26:01 INFO - 'tests': ['basic_compositor_video']}, 08:26:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:26:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:26:01 INFO - 'tests': ['a11yr', 08:26:01 INFO - 'ts_paint', 08:26:01 INFO - 'tpaint', 08:26:01 INFO - 'sessionrestore', 08:26:01 INFO - 'sessionrestore_no_auto_restore', 08:26:01 INFO - 'tabpaint']}, 08:26:01 INFO - 'other-e10s': {'tests': ['a11yr', 08:26:01 INFO - 'ts_paint', 08:26:01 INFO - 'tpaint', 08:26:01 INFO - 'sessionrestore', 08:26:01 INFO - 'sessionrestore_no_auto_restore', 08:26:01 INFO - 'tabpaint']}, 08:26:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:26:01 INFO - 'ts_paint', 08:26:01 INFO - 'tpaint', 08:26:01 INFO - 'sessionrestore', 08:26:01 INFO - 'sessionrestore_no_auto_restore', 08:26:01 INFO - 'tabpaint']}, 08:26:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:26:01 INFO - 'ts_paint', 08:26:01 INFO - 'tpaint', 08:26:01 INFO - 'sessionrestore', 08:26:01 INFO - 'sessionrestore_no_auto_restore', 08:26:01 INFO - 'tabpaint']}, 08:26:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:26:01 INFO - 'tests': ['a11yr', 08:26:01 INFO - 'ts_paint', 08:26:01 INFO - 'tpaint', 08:26:01 INFO - 'sessionrestore', 08:26:01 INFO - 'sessionrestore_no_auto_restore', 08:26:01 INFO - 'tabpaint']}, 08:26:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:26:01 INFO - 'tests': ['a11yr', 08:26:01 INFO - 'ts_paint', 08:26:01 INFO - 'tpaint', 08:26:01 INFO - 'sessionrestore', 08:26:01 INFO - 'sessionrestore_no_auto_restore', 08:26:01 INFO - 'tabpaint']}, 08:26:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:26:01 INFO - 'tests': ['tsvgx', 08:26:01 INFO - 'tsvgr_opacity', 08:26:01 INFO - 'tart', 08:26:01 INFO - 'tscrollx', 08:26:01 INFO - 'cart']}, 08:26:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:26:01 INFO - 'tsvgr_opacity', 08:26:01 INFO - 'tart', 08:26:01 INFO - 'tscrollx', 08:26:01 INFO - 'cart']}, 08:26:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:01 INFO - 'talos_options': ['--disable-e10s'], 08:26:01 INFO - 'tests': ['tp5o']}, 08:26:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:01 INFO - 'tests': ['tp5o']}, 08:26:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:01 INFO - 'talos_options': ['--disable-e10s', 08:26:01 INFO - '--xperf_path', 08:26:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:26:01 INFO - 'tests': ['tp5n']}, 08:26:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:01 INFO - 'talos_options': ['--xperf_path', 08:26:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:26:01 INFO - 'tests': ['tp5n']}}, 08:26:01 INFO - 'talos.zip': {'path': '', 08:26:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:26:01 INFO - Running post-action listener: _resource_record_post_action 08:26:01 INFO - [mozharness: 2016-10-03 15:26:01.325374Z] Finished populate-webroot step (success) 08:26:01 INFO - [mozharness: 2016-10-03 15:26:01.325506Z] Running create-virtualenv step. 08:26:01 INFO - Running pre-action listener: _resource_record_pre_action 08:26:01 INFO - Running main action method: create_virtualenv 08:26:01 INFO - Creating virtualenv /builds/slave/test/build/venv 08:26:01 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 08:26:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:26:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:26:01 INFO - Using real prefix '/tools/python27' 08:26:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:26:02 INFO - Installing distribute.............................................................................................................................................................................................done. 08:26:05 INFO - Installing pip.................done. 08:26:05 INFO - Return code: 0 08:26:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:26:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:26:05 INFO - Reading from file tmpfile_stdout 08:26:05 INFO - Output received: 08:26:05 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) 08:26:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:26: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')]} 08:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26: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')]} 08:26:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a79490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa769d5a560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488dd30>, '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 0x1046b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104885b20>, '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.GLltipmYjo/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.eTniLqILqn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 08:26: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', 'pip>=1.5'] in /builds/slave/test/build 08:26: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 pip>=1.5 08:26:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLltipmYjo/Render', 08:26:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:05 INFO - 'HOME': '/Users/cltbld', 08:26:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:05 INFO - 'LOGNAME': 'cltbld', 08:26:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:05 INFO - 'MOZ_NO_REMOTE': '1', 08:26:05 INFO - 'NO_EM_RESTART': '1', 08:26:05 INFO - 'PAGER': '/bin/cat', 08:26:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:05 INFO - 'PWD': '/builds/slave/test', 08:26:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:05 INFO - 'SHELL': '/bin/bash', 08:26:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTniLqILqn/Listeners', 08:26:05 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 08:26:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:05 INFO - 'USER': 'cltbld', 08:26:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:05 INFO - 'XPC_FLAGS': '0x0', 08:26:05 INFO - 'XPC_SERVICE_NAME': '0', 08:26:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:05 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 08:26:05 INFO - Cleaning up... 08:26:05 INFO - Return code: 0 08:26:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:26: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')]} 08:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26: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')]} 08:26:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26: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', '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 0x104a79490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa769d5a560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488dd30>, '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 0x1046b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104885b20>, '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.GLltipmYjo/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.eTniLqILqn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 08:26: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:26: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 psutil>=3.1.1 08:26:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLltipmYjo/Render', 08:26:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:05 INFO - 'HOME': '/Users/cltbld', 08:26:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:05 INFO - 'LOGNAME': 'cltbld', 08:26:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:05 INFO - 'MOZ_NO_REMOTE': '1', 08:26:05 INFO - 'NO_EM_RESTART': '1', 08:26:05 INFO - 'PAGER': '/bin/cat', 08:26:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:05 INFO - 'PWD': '/builds/slave/test', 08:26:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:05 INFO - 'SHELL': '/bin/bash', 08:26:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTniLqILqn/Listeners', 08:26:05 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 08:26:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:05 INFO - 'USER': 'cltbld', 08:26:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:05 INFO - 'XPC_FLAGS': '0x0', 08:26:05 INFO - 'XPC_SERVICE_NAME': '0', 08:26:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:05 INFO - Downloading/unpacking psutil>=3.1.1 08:26: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 08:26: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 08:26: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 08:26: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 08:26: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 08:26: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 08:26:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:26:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:26:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:26:09 INFO - Installing collected packages: psutil 08:26:09 INFO - Running setup.py install for psutil 08:26:09 INFO - building 'psutil._psutil_osx' extension 08:26:09 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 08:26:10 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 08:26:10 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 08:26:10 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 08:26:10 INFO - building 'psutil._psutil_posix' extension 08:26:10 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 08:26:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:26:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:26:10 INFO - ^ 08:26:10 INFO - 1 warning generated. 08:26:10 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 08:26:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:26:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:26:10 INFO - Successfully installed psutil 08:26:10 INFO - Cleaning up... 08:26:10 INFO - Return code: 0 08:26:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:26:10 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')]} 08:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:10 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')]} 08:26:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:10 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 0x104a79490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa769d5a560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488dd30>, '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 0x1046b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104885b20>, '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.GLltipmYjo/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.eTniLqILqn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 08:26:10 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 08:26:10 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 08:26:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLltipmYjo/Render', 08:26:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:10 INFO - 'HOME': '/Users/cltbld', 08:26:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:10 INFO - 'LOGNAME': 'cltbld', 08:26:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:10 INFO - 'MOZ_NO_REMOTE': '1', 08:26:10 INFO - 'NO_EM_RESTART': '1', 08:26:10 INFO - 'PAGER': '/bin/cat', 08:26:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:10 INFO - 'PWD': '/builds/slave/test', 08:26:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:10 INFO - 'SHELL': '/bin/bash', 08:26:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTniLqILqn/Listeners', 08:26:10 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 08:26:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:10 INFO - 'USER': 'cltbld', 08:26:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:10 INFO - 'XPC_FLAGS': '0x0', 08:26:10 INFO - 'XPC_SERVICE_NAME': '0', 08:26:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:26:11 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 08:26:11 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 08:26:11 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 08:26:11 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 08:26:11 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 08:26:11 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 08:26:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:26:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:26:13 INFO - Installing collected packages: mozsystemmonitor 08:26:13 INFO - Running setup.py install for mozsystemmonitor 08:26:13 INFO - Successfully installed mozsystemmonitor 08:26:13 INFO - Cleaning up... 08:26:14 INFO - Return code: 0 08:26:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:26: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')]} 08:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26: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')]} 08:26:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104a79490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa769d5a560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488dd30>, '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 0x1046b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104885b20>, '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.GLltipmYjo/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.eTniLqILqn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 08:26:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:26:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:26:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLltipmYjo/Render', 08:26:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:14 INFO - 'HOME': '/Users/cltbld', 08:26:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:14 INFO - 'LOGNAME': 'cltbld', 08:26:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:14 INFO - 'MOZ_NO_REMOTE': '1', 08:26:14 INFO - 'NO_EM_RESTART': '1', 08:26:14 INFO - 'PAGER': '/bin/cat', 08:26:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:14 INFO - 'PWD': '/builds/slave/test', 08:26:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:14 INFO - 'SHELL': '/bin/bash', 08:26:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTniLqILqn/Listeners', 08:26:14 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 08:26:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:14 INFO - 'USER': 'cltbld', 08:26:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:14 INFO - 'XPC_FLAGS': '0x0', 08:26:14 INFO - 'XPC_SERVICE_NAME': '0', 08:26:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:14 INFO - Downloading/unpacking jsonschema==2.5.1 08:26:14 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 08:26:14 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 08:26:14 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 08:26:14 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 08:26:14 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 08:26:14 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 08:26:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:26:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:26:16 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 08:26:16 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 08:26:16 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 08:26:16 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 08:26:16 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 08:26:16 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 08:26:17 INFO - Downloading functools32-3.2.3-2.tar.gz 08:26:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:26:17 INFO - warning: no files found matching '*.txt' 08:26:17 INFO - no previously-included directories found matching 'build' 08:26:17 INFO - no previously-included directories found matching 'dist' 08:26:17 INFO - no previously-included directories found matching '.git*' 08:26:17 INFO - Installing collected packages: jsonschema, functools32 08:26:17 INFO - Running setup.py install for functools32 08:26:17 INFO - warning: no files found matching '*.txt' 08:26:17 INFO - no previously-included directories found matching 'build' 08:26:17 INFO - no previously-included directories found matching 'dist' 08:26:17 INFO - no previously-included directories found matching '.git*' 08:26:17 INFO - Successfully installed jsonschema functools32 08:26:17 INFO - Cleaning up... 08:26:17 INFO - Return code: 0 08:26:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:26:17 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')]} 08:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:17 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')]} 08:26:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:17 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 0x104a79490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa769d5a560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488dd30>, '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 0x1046b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104885b20>, '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.GLltipmYjo/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.eTniLqILqn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 08:26:17 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 08:26:17 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 08:26:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLltipmYjo/Render', 08:26:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:17 INFO - 'HOME': '/Users/cltbld', 08:26:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:17 INFO - 'LOGNAME': 'cltbld', 08:26:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:17 INFO - 'MOZ_NO_REMOTE': '1', 08:26:17 INFO - 'NO_EM_RESTART': '1', 08:26:17 INFO - 'PAGER': '/bin/cat', 08:26:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:17 INFO - 'PWD': '/builds/slave/test', 08:26:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:17 INFO - 'SHELL': '/bin/bash', 08:26:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTniLqILqn/Listeners', 08:26:17 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 08:26:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:17 INFO - 'USER': 'cltbld', 08:26:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:17 INFO - 'XPC_FLAGS': '0x0', 08:26:17 INFO - 'XPC_SERVICE_NAME': '0', 08:26:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:26:17 INFO - Cleaning up... 08:26:17 INFO - Return code: 0 08:26:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:26:17 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')]} 08:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:17 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')]} 08:26:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:17 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 0x104a79490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa769d5a560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488dd30>, '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 0x1046b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104885b20>, '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.GLltipmYjo/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.eTniLqILqn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 08:26:17 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 08:26:17 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 08:26:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLltipmYjo/Render', 08:26:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:17 INFO - 'HOME': '/Users/cltbld', 08:26:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:17 INFO - 'LOGNAME': 'cltbld', 08:26:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:17 INFO - 'MOZ_NO_REMOTE': '1', 08:26:17 INFO - 'NO_EM_RESTART': '1', 08:26:17 INFO - 'PAGER': '/bin/cat', 08:26:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:17 INFO - 'PWD': '/builds/slave/test', 08:26:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:17 INFO - 'SHELL': '/bin/bash', 08:26:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTniLqILqn/Listeners', 08:26:17 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 08:26:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:17 INFO - 'USER': 'cltbld', 08:26:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:17 INFO - 'XPC_FLAGS': '0x0', 08:26:17 INFO - 'XPC_SERVICE_NAME': '0', 08:26:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:18 INFO - Downloading/unpacking blobuploader==1.2.4 08:26:18 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 08:26:18 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 08:26:18 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 08:26:18 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 08:26:18 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 08:26:18 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 08:26:21 INFO - Downloading blobuploader-1.2.4.tar.gz 08:26:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:26:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:26:21 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 08:26:21 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 08:26:21 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 08:26:21 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 08:26:21 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 08:26:21 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 08:26:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:26:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:26:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:22 INFO - Downloading docopt-0.6.1.tar.gz 08:26:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:26:22 INFO - Installing collected packages: blobuploader, requests, docopt 08:26:22 INFO - Running setup.py install for blobuploader 08:26:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:26:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:26:22 INFO - Running setup.py install for requests 08:26:23 INFO - Running setup.py install for docopt 08:26:23 INFO - Successfully installed blobuploader requests docopt 08:26:23 INFO - Cleaning up... 08:26:23 INFO - Return code: 0 08:26:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:26:23 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')]} 08:26:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:23 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')]} 08:26:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:23 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 0x104a79490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa769d5a560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488dd30>, '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 0x1046b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104885b20>, '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.GLltipmYjo/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.eTniLqILqn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 08:26:23 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 08:26:23 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 08:26:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLltipmYjo/Render', 08:26:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:23 INFO - 'HOME': '/Users/cltbld', 08:26:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:23 INFO - 'LOGNAME': 'cltbld', 08:26:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:23 INFO - 'MOZ_NO_REMOTE': '1', 08:26:23 INFO - 'NO_EM_RESTART': '1', 08:26:23 INFO - 'PAGER': '/bin/cat', 08:26:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:23 INFO - 'PWD': '/builds/slave/test', 08:26:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:23 INFO - 'SHELL': '/bin/bash', 08:26:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTniLqILqn/Listeners', 08:26:23 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 08:26:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:23 INFO - 'USER': 'cltbld', 08:26:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:23 INFO - 'XPC_FLAGS': '0x0', 08:26:23 INFO - 'XPC_SERVICE_NAME': '0', 08:26:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:26:23 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-3UMtZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:26:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-2P1tFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:26:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-olVRK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:26:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-3BzPkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:26:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-5jZouk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:26:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-VkxwIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:26:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-0rKsRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:26:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-8jl1Tq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:26:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-0SLh51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:26:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-qQRmpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:26:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-ApJaKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:26:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-iwIxjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:26:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-ME5One-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:26:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-yKLmEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:26:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-c3flgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:26:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-2fvkbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:26:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-8uPenW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:26:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:26:25 INFO - Running setup.py install for manifestparser 08:26:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:26:25 INFO - Running setup.py install for mozcrash 08:26:26 INFO - Running setup.py install for mozdebug 08:26:26 INFO - Running setup.py install for mozdevice 08:26:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Running setup.py install for mozfile 08:26:26 INFO - Running setup.py install for mozhttpd 08:26:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Running setup.py install for mozinfo 08:26:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Running setup.py install for mozInstall 08:26:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Running setup.py install for mozleak 08:26:27 INFO - Running setup.py install for mozlog 08:26:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:26:27 INFO - Running setup.py install for moznetwork 08:26:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:26:27 INFO - Running setup.py install for mozprocess 08:26:27 INFO - Running setup.py install for mozprofile 08:26:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:26:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:26:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:26:27 INFO - Running setup.py install for mozrunner 08:26:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:26:27 INFO - Running setup.py install for mozscreenshot 08:26:28 INFO - Running setup.py install for moztest 08:26:28 INFO - Running setup.py install for mozversion 08:26:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:26:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:26:28 INFO - Cleaning up... 08:26:28 INFO - Return code: 0 08:26:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:26:28 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')]} 08:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:28 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')]} 08:26:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:28 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 0x104a79490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa769d5a560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488dd30>, '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 0x1046b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104885b20>, '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.GLltipmYjo/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.eTniLqILqn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 08:26:28 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 08:26:28 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 08:26:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLltipmYjo/Render', 08:26:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:28 INFO - 'HOME': '/Users/cltbld', 08:26:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:28 INFO - 'LOGNAME': 'cltbld', 08:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:28 INFO - 'MOZ_NO_REMOTE': '1', 08:26:28 INFO - 'NO_EM_RESTART': '1', 08:26:28 INFO - 'PAGER': '/bin/cat', 08:26:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:28 INFO - 'PWD': '/builds/slave/test', 08:26:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:28 INFO - 'SHELL': '/bin/bash', 08:26:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTniLqILqn/Listeners', 08:26:28 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 08:26:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:28 INFO - 'USER': 'cltbld', 08:26:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:28 INFO - 'XPC_FLAGS': '0x0', 08:26:28 INFO - 'XPC_SERVICE_NAME': '0', 08:26:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:26:28 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-_zWXzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:26:28 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)) 08:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:26:28 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-jEdt1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:26:28 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)) 08:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:26:28 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-9AUOqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:26:28 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)) 08:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:26:28 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-Rrih31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:26:29 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)) 08:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:26:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-1D9ohI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:26:29 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)) 08:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:26:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-1Bc_G7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:26:29 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)) 08:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:26:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-tcVpLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:26:29 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)) 08:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:26:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-rZ0_Hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:26:29 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)) 08:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:26:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-lOCdt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:26:29 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)) 08:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:26:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-9oD7yH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:26:29 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)) 08:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:26:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-rGBOeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:26:29 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)) 08:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:26:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-9S9wgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:26:29 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)) 08:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:26:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-7vdqO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:26:30 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)) 08:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:26:30 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-OIfTkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:26:30 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)) 08:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:26:30 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-n5Lm3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:26:30 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)) 08:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:26:30 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-jEUssL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:26:30 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)) 08:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:26:30 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-978C7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:26:30 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)) 08:26:30 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)) 08:26:30 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)) 08:26:30 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)) 08:26:30 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)) 08:26:30 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)) 08:26:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:32 INFO - Downloading blessings-1.6.tar.gz 08:26:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:26:33 INFO - Installing collected packages: blessings 08:26:33 INFO - Running setup.py install for blessings 08:26:33 INFO - Successfully installed blessings 08:26:33 INFO - Cleaning up... 08:26:33 INFO - Return code: 0 08:26:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:26:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:26:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:26:33 INFO - Reading from file tmpfile_stdout 08:26:33 INFO - Current package versions: 08:26:33 INFO - blessings == 1.6 08:26:33 INFO - blobuploader == 1.2.4 08:26:33 INFO - docopt == 0.6.1 08:26:33 INFO - functools32 == 3.2.3-2 08:26:33 INFO - jsonschema == 2.5.1 08:26:33 INFO - manifestparser == 1.1 08:26:33 INFO - mozInstall == 1.12 08:26:33 INFO - mozcrash == 0.17 08:26:33 INFO - mozdebug == 0.1 08:26:33 INFO - mozdevice == 0.48 08:26:33 INFO - mozfile == 1.2 08:26:33 INFO - mozhttpd == 0.7 08:26:33 INFO - mozinfo == 0.9 08:26:33 INFO - mozleak == 0.1 08:26:33 INFO - mozlog == 3.4 08:26:33 INFO - moznetwork == 0.27 08:26:33 INFO - mozprocess == 0.23 08:26:33 INFO - mozprofile == 0.28 08:26:33 INFO - mozrunner == 6.12 08:26:33 INFO - mozscreenshot == 0.1 08:26:33 INFO - mozsystemmonitor == 0.3 08:26:33 INFO - moztest == 0.7 08:26:33 INFO - mozversion == 1.4 08:26:33 INFO - psutil == 3.1.1 08:26:33 INFO - requests == 1.2.3 08:26:33 INFO - wsgiref == 0.1.2 08:26:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:26:33 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')]} 08:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:33 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')]} 08:26:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:33 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 0x104a79490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa769d5a560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488dd30>, '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 0x1046b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104885b20>, '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.GLltipmYjo/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.eTniLqILqn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 08:26:33 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 08:26:33 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 08:26:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLltipmYjo/Render', 08:26:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:33 INFO - 'HOME': '/Users/cltbld', 08:26:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:33 INFO - 'LOGNAME': 'cltbld', 08:26:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:33 INFO - 'MOZ_NO_REMOTE': '1', 08:26:33 INFO - 'NO_EM_RESTART': '1', 08:26:33 INFO - 'PAGER': '/bin/cat', 08:26:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:33 INFO - 'PWD': '/builds/slave/test', 08:26:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:33 INFO - 'SHELL': '/bin/bash', 08:26:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTniLqILqn/Listeners', 08:26:33 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 08:26:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:33 INFO - 'USER': 'cltbld', 08:26:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:33 INFO - 'XPC_FLAGS': '0x0', 08:26:33 INFO - 'XPC_SERVICE_NAME': '0', 08:26:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:33 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)) 08:26:33 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)) 08:26:33 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)) 08:26:33 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)) 08:26:33 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)) 08:26:33 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)) 08:26:33 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)) 08:26:33 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)) 08:26:33 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)) 08:26:33 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)) 08:26:33 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)) 08:26:33 INFO - Cleaning up... 08:26:33 INFO - Return code: 0 08:26:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:26:33 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')]} 08:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:33 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')]} 08:26:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:33 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 0x104a79490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa769d5a560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488dd30>, '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 0x1046b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104885b20>, '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.GLltipmYjo/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.eTniLqILqn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 08:26:33 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 08:26:33 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 08:26:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLltipmYjo/Render', 08:26:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:33 INFO - 'HOME': '/Users/cltbld', 08:26:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:33 INFO - 'LOGNAME': 'cltbld', 08:26:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:33 INFO - 'MOZ_NO_REMOTE': '1', 08:26:33 INFO - 'NO_EM_RESTART': '1', 08:26:33 INFO - 'PAGER': '/bin/cat', 08:26:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:33 INFO - 'PWD': '/builds/slave/test', 08:26:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:33 INFO - 'SHELL': '/bin/bash', 08:26:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTniLqILqn/Listeners', 08:26:33 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 08:26:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:33 INFO - 'USER': 'cltbld', 08:26:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:33 INFO - 'XPC_FLAGS': '0x0', 08:26:33 INFO - 'XPC_SERVICE_NAME': '0', 08:26:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:26:34 INFO - Cleaning up... 08:26:34 INFO - Return code: 0 08:26:34 INFO - Running post-action listener: _resource_record_post_action 08:26:34 INFO - Running post-action listener: _start_resource_monitoring 08:26:34 INFO - Starting resource monitoring. 08:26:34 INFO - [mozharness: 2016-10-03 15:26:34.175787Z] Finished create-virtualenv step (success) 08:26:34 INFO - [mozharness: 2016-10-03 15:26:34.176817Z] Running install step. 08:26:34 INFO - Running pre-action listener: _resource_record_pre_action 08:26:34 INFO - Running main action method: install 08:26:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:26:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:26:34 INFO - Reading from file tmpfile_stdout 08:26:34 INFO - Detecting whether we're running mozinstall >=1.0... 08:26:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:26:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:26:34 INFO - Reading from file tmpfile_stdout 08:26:34 INFO - Output received: 08:26:34 INFO - Usage: mozinstall [options] installer 08:26:34 INFO - Options: 08:26:34 INFO - -h, --help show this help message and exit 08:26:34 INFO - -d DEST, --destination=DEST 08:26:34 INFO - Directory to install application into. [default: 08:26:34 INFO - "/builds/slave/test"] 08:26:34 INFO - --app=APP Application being installed. [default: firefox] 08:26:34 INFO - mkdir: /builds/slave/test/build/application 08:26:34 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'] 08:26:34 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 08:27:09 INFO - Reading from file tmpfile_stdout 08:27:09 INFO - Output received: 08:27:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:27:09 INFO - Running post-action listener: _resource_record_post_action 08:27:09 INFO - [mozharness: 2016-10-03 15:27:09.232992Z] Finished install step (success) 08:27:09 INFO - [mozharness: 2016-10-03 15:27:09.233105Z] Running run-tests step. 08:27:09 INFO - Running pre-action listener: _resource_record_pre_action 08:27:09 INFO - Running main action method: run_tests 08:27:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:27:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:27:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:27:09 INFO - 2016-10-03 08:27:09.246 screenresolution[1649:8992] starting screenresolution argv=screenresolution get 08:27:09 INFO - 2016-10-03 08:27:09.248 screenresolution[1649:8992] Display 0: 1600x1200x32@60 08:27:09 INFO - 2016-10-03 08:27:09.254 screenresolution[1650:8994] starting screenresolution argv=screenresolution list 08:27:09 INFO - Available Modes on Display 0 08:27:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:27:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:27:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:27:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:27:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:27:09 INFO - Intel Iris: 08:27:09 INFO - Chipset Model: Intel Iris 08:27:09 INFO - Type: GPU 08:27:09 INFO - Bus: Built-In 08:27:09 INFO - VRAM (Dynamic, Max): 1536 MB 08:27:09 INFO - Vendor: Intel (0x8086) 08:27:09 INFO - Device ID: 0x0a2e 08:27:09 INFO - Revision ID: 0x0009 08:27:09 INFO - Displays: 08:27:09 INFO - Display: 08:27:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:27:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:27:09 INFO - Display Serial Number: 1600x1200 60 08:27:09 INFO - Main Display: Yes 08:27:09 INFO - Mirror: Off 08:27:09 INFO - Online: Yes 08:27:09 INFO - Rotation: Supported 08:27:09 INFO - Return code: 0 08:27:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:27:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:27:09 INFO - Python 2.7.3 08:27:09 INFO - Return code: 0 08:27:09 INFO - grabbing minidump binary from tooltool 08:27: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')]} 08:27:09 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 0x10488dd30>, '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 0x1046b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104885b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:27:09 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 08:27:09 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 08:27:09 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 08:27:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:27:09 INFO - Return code: 0 08:27:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:27:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:27:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:27:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:27:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:27:09 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/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0037', '--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 08:27:09 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/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0037 --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 08:27:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLltipmYjo/Render', 08:27:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:09 INFO - 'HOME': '/Users/cltbld', 08:27:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:09 INFO - 'LOGNAME': 'cltbld', 08:27:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:27:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:27:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:09 INFO - 'MOZ_NO_REMOTE': '1', 08:27:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:27:09 INFO - 'NO_EM_RESTART': '1', 08:27:09 INFO - 'PAGER': '/bin/cat', 08:27:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:09 INFO - 'PWD': '/builds/slave/test', 08:27:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:27:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:09 INFO - 'SHELL': '/bin/bash', 08:27:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eTniLqILqn/Listeners', 08:27:09 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 08:27:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:09 INFO - 'USER': 'cltbld', 08:27:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:09 INFO - 'XPC_FLAGS': '0x0', 08:27:09 INFO - 'XPC_SERVICE_NAME': '0', 08:27:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:09 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/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0037', '--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 08:27:09 INFO - mozversion application_buildid: 20161003072255 08:27:09 INFO - mozversion application_changeset: 7aeb526759fe3a57a87f0af96a35d64b4f23f4ab 08:27:09 INFO - mozversion application_display_name: Nightly 08:27:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:27:09 INFO - mozversion application_name: Firefox 08:27:09 INFO - mozversion application_remotingname: firefox 08:27:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:27:09 INFO - mozversion application_vendor: Mozilla 08:27:09 INFO - mozversion application_version: 52.0a1 08:27:09 INFO - mozversion platform_buildid: 20161003072255 08:27:09 INFO - mozversion platform_changeset: 7aeb526759fe3a57a87f0af96a35d64b4f23f4ab 08:27:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:27:09 INFO - mozversion platform_version: 52.0a1 08:27:09 INFO - using testdate: 1475508429 08:27:09 INFO - actual date: 1475508429 08:27:09 INFO - starting webserver on 'localhost:49272' 08:27:09 INFO - SUITE-START | Running 2 tests 08:27:09 INFO - TEST-START | dromaeo_css 08:27:09 INFO - operating with platform_type : mac_ 08:27:09 INFO - Initialising browser for dromaeo_css test... 08:27:09 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpf9ijVx/profile http://localhost:49272/getInfo.html) 08:27:12 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 08:27:12 INFO - PROCESS | 1660 | colorDepth:24 08:27:12 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 08:27:12 INFO - PROCESS | 1660 | __metrics 08:27:12 INFO - PROCESS | 1660 | 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" 08:27:12 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:27:13 INFO - TEST-INFO | 1660: exit 0 08:27:13 INFO - Browser initialized. 08:27:13 INFO - Running cycle 1/1 for dromaeo_css test... 08:27:13 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpf9ijVx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:27:25 INFO - PROCESS | 1663 | RSS: Main: 235974656 08:27:25 INFO - PROCESS | 1663 | 08:28:16 INFO - PROCESS | 1663 | 1475508496842 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] 08:30:29 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 08:30:31 INFO - PROCESS | 1663 | RSS: Main: 348573696 08:30:31 INFO - PROCESS | 1663 | 08:33:33 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 08:33:34 INFO - PROCESS | 1663 | RSS: Main: 380981248 08:33:34 INFO - PROCESS | 1663 | 08:36:36 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 08:36:37 INFO - PROCESS | 1663 | RSS: Main: 340721664 08:36:37 INFO - PROCESS | 1663 | 08:39:39 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 08:39:40 INFO - PROCESS | 1663 | RSS: Main: 335495168 08:39:40 INFO - PROCESS | 1663 | 08:42:43 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 08:42:44 INFO - PROCESS | 1663 | RSS: Main: 327041024 08:42:44 INFO - PROCESS | 1663 | 08:45:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 08:45:47 INFO - PROCESS | 1663 | RSS: Main: 408854528 08:45:47 INFO - PROCESS | 1663 | 08:45:47 INFO - PROCESS | 1663 | __start_tp_report 08:45:47 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 08:45:47 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 08:45:47 INFO - PROCESS | 1663 | |i|pagename|runs| 08:45:47 INFO - PROCESS | 1663 | |0;dojo.html;3277.88527401541;3279.3441311737656;3294.5058241263823;3297.373499035183;3332.4834650629145;5533.446655334465;5600.299962504681;5602.747876345564;5693.9715301423485;5742.138679198117;5619.5785316101255;5696.316442026952;5701.57238207134;5714.1428785682065;5714.771409143629;5100.464451232613;5136.537711605954;5147.279380886679;5177.197534382177;5196.818111366084;5243.2659427680155;5250.789968401259;5254.973725131388;5261.368635763713;5265.815696450625;5127.33344665193;5174.948250517485;5183.896322073556;5237.423883372845;5248.29148065012;5252.6323157379;5261.053010458116;5268.47315268472;5269.762860671279;5282.498162674539;5063.987202559503;5088.567471764904;5151.150060240056;5158.40678321994;5202.4277329493725;5580.190872323531;5601.131824567198;5651.8304450866535;5663.2637757091725;5676.063449530822;6188.10272510488;6212.565120441571;6216.658083805389;6221.782237621706;6233.189685340922;4592.357070010201;6144.0783882417545;6162.921488739453;6248.531360147962;6273.529485288622;5714.51426628737;5756.424357564294;5760.481556659921;5776.364599894008;5832.037713777264;6200.658963756947;6204.348543402878;6213.565050446516;6214.9689251554355;6231.127642130105;5928.762849486058;5990.820275391745;6016.939830601726;6087.147799308101;6109.908351374733;6115.848865095035;6132.8756461173125;6143.894099062211;6151.507879369639;6220.315765265817;6195.721192546346;6199.721012554446;6202.72087756052;6211.254649442096;6212.534059945522;6190.0405437157315;6197.81406557804;6199.535034872402;6204.286507051656;6206.844828879314;4765.761711914391;4822.228443449031;4827.106985207678;4848.7090774553635;4874.390701162355;4362.083962367875;4376.8905777355185;4416.138852923713;4424.358468022118;4465.977670111629;5740.741666625012;5756.510696590753;5760.855978600569;5766.711664416763;5780.017397042513;6020.066889632114;6051.969740151271;6112.908306375408;6141.877162456726;6233.688315584293;4714.599259062949;5739.2252045973255;5752.9712351437975;5795.1886735857015;5805.303363596311;5341.813036543703;5350.224217488442;5350.5987050971335;5367.490088441592;5399.6490228135835;6175.104609831549;6199.659018754011;6200.348963358782;6200.441960223601;6209.472194863307;6040.848978775566;6125.021800333928;6141.1484926909025;6144.095330447575;6152.64641778808;6172.907406388818;6201.131841542097;6206.906896396648;6212.9068063978175;6218.875622487615;6124.081387791689;6131.785387511416;6150.661713605616;6151.446369826737;6160.722767475385;5352.063388906848;6112.2970858351855;6143.481172719164;6193.194884665145;6257.155284036597;5066.923996139987;6043.214382130295;6053.667048312385;6101.694915254131;6116.480099191618;6033.637981720935;6034.63792172471;6037.09443583466;6041.607295525777;6044.274687037584;5763.8559036024435;5813.82558523245;5851.180834683233;5894.793682221101;5896.262967129109;5993.951058564821;6015.157877897185;6017.819465416044;6061.818145455643;6124.571280010535;5894.0274854648505;5895.94104058954;5902.468777810018;5909.349971503218;5961.433663801932 08:45:47 INFO - PROCESS | 1663 | |1;ext.html;479.4151135614549;481.56418441310615;529.8119167695469;533.578473006325;534.9491798279162;30394.240143996412;34591;40235.396469052954;41528;43608.3458748119;42105;42529.36205956913;42555.57444425555;42610.360844587354;42737.78631106848;9618.903810961889;9812.901870981306;9814.362066465692;9814.65648702292;9825.115739583436;9729.902700973005;9858.260630452745;9858.507074646275;9863.408195508293;9889.802203955916;9781.706548803511;9820.705378838611;9822.901770982271;9830.901690983106;9868.161206297438;9784.217262618973;9844.60621575136;9846.261530385249;9850.408975461496;9867.60529578816;9527.285453591008;9627.56675949584;9630.807383852354;9640.855387169127;9641.42151470914;40235.994100147436;40410.39384409236;40453.393199102036;40482.79758601217;40496.79751601223;3538.4692296155526;3903.5901230370687;3958.366661334173;3963.207358528306;4060.573639767841;30605.38789224203;30799.384012319853;31070.689293107233;31528.211794705087;31939.6806031939;3158.09994151668;3191.7127458528844;3209.9135561884727;3308.3052558962645;3339.716124129452;369650;371354.1432292821;373678;374019;375681;59891;60191;60268;60291;60313.69843150844;63491.36508634947;63673;63713;63718.36281637124;63750;15720.371185152466;15958.808238352376;15995.040297582182;16002;16047.679046419007;12310.81533776994;12340.444679989421;12369.567065152676;12388.876111238773;12412;14032.228227447384;15663.530094097196;17105.914470427568;17148.742768858476;17219.22513486896;6910.48171387148;6931.930680693229;6947.89578156328;6970.372666460043;7060.929390706027;886.7517095213351;898.766320756595;941.8304705153138;958.990410095904;1058.4919238765435;28389.148325550268;29185.854070729936;29506;29591.408171836443;29909.850450747606;906.9501177435304;1032.2464600841279;1050.7128767260199;1070.3631339353071;1144.2047776795048;14674.486392976194;15160.772588411182;15260.847391526164;15516.844831551765;15596.142212178213;626261;632665;632882;633313;634061;56731;56779.14831277534;56867.14699279431;56873.14690279649;56878;1218.939053047344;1254.6361555149088;1325.4035683942266;1357.4095268558208;1376.2636989210923;28760.856195718887;29581.704182957896;29621.407571848013;30453.086407407813;30525.694743052285;41118.79440602778;49999.75000124976;50035.74982125066;50057.49942500674;50063.24905126499;391103.26690199337;519323;521863;522036;522240;25233;25514;25521.872390637927;25537;25538.74461255438;44680;44888.10223795571;45180.096398072514;45232;45609.087818242784;37158;37709.4343584841;37716.81141594274;38428;38654.22691546097;36854.63145368512;36883.89348319555;36930.81534592417;37796.811015944746;38077.809610951765;36729.63270367262;36972.44541331828;37434.62565374311;37473.2505349897;37527;31497.212569685023;33086.76390064176;33232.833835830665;33341.33317333688;33410.49884251689;22436.66345004859;24495.26380245447;24579.508409832062;24747.87626061858;24761.304899872768 08:45:47 INFO - PROCESS | 1663 | |2;jquery.html;350.45679197244266;369.3960374787223;370.2262271851827;377.09309111586634;402.4265421773973;14530;15035.924820375896;19982.7002594961;20694.586108277825;21257.362279131612;19472.31846885359;20559.8692071936;20573.485662858435;20734.274300399487;20958.89520552395;5433.320834895638;5852.414758524134;6238.4385405313515;6240.188775459184;6263.342349053352;4134.710570260083;4725.976370118162;6086.665233412161;6218.067289906505;6819.420349270318;6737.494687898403;6801.591904485746;6821.829454263659;6829.078074459959;6837.658117094125;5793.768249270024;6137.324894261627;6139.293981192175;6183.1961844960315;6815.591064536119;6004.819855404301;6005.108390856866;6009;6014.578979471439;6027.156198132265;11996.820047699292;12495;13189.802152967713;13387.665308367272;13449.32753362328;2051.497383141125;2246.86518808872;2288.2563166970635;2458.7418321076207;2651.6287719719253;9914.454704991223;9946.254030947708;10110.39337639744;10449.895501044968;10498.580056797718;1799.2443173867007;1877.7371168036486;1923.2397276987429;1929.778075521319;1929.778075521319;116061;117169;119574.4021279888;121052;122069.63781338319;9466.1953733932;9801.166900813369;9803.509824508747;9952.850707239397;10185.032421919906;12878.742425151444;13440.395182216824;13457.663558411117;13474.461021559027;14225.217613031382;8113.432059755761;8204.753857384288;8223.751618110813;8241.299489543337;8399;8142.633581488847;8158.143394943564;8164.101948785629;8337.583120843934;8339.416240863082;4442.622377097919;4477.283634618446;4501.662375321864;4594.448666160015;4596.770161491912;1845.3449025595721;1885.0386302985576;1891.7446144770556;1899.078946710844;1903.181631898297;823.5305875650942;878.2466966061444;974.3569244298728;1013.5692330759398;1068.2949253492814;19934.401967940987;21541.46146346354;21559.892200538896;21573.352799416043;21678.89160554187;895.4000819450983;914.8124634449922;921.0145144695111;928.832810094176;939.9425646148084;10533.473326333806;10831.67504974852;11340.546378144782;11424.885751142549;11441.370724610226;579290;581192;588397;593747;605749;118781;130263;131399;138720;141890;946.6024269806697;996.1781530237438;997.3666721631816;997.4613708597421;1000.3297790480395;19132.426027219204;19354;19459.318923837596;19511.902440487705;19543.60912781765;54007.72996134994;55780.721096394256;56145;56685.71657141688;56762.7161864188;60174.699126504085;74878;80335.59832200801;83945.16054839618;84067.5796621013;6998.950157476215;7395.556266624019;7438.962805185939;7951.960240198762;8299.792505187419;11959.581414650445;12348.259104453762;12648.924841388218;13371.665708357368;13410.597682069554;11094.223404361617;11321.485498070904;11478.885211148116;11516.596919107791;11733.296002239893;11188;11333.036691881283;11449.885501145216;11585.6524304271;11627.6511704649;9269;9551.61793528279;11582.594609188638;11637.418129093343;11711.941440292743;7998.760037198894;8048.195180481905;8362.205590468895;8401.915980840358;8419.242268195892;10338.172946164439;11203.775924481628;11680.47437865281;11686.24039437434;11727.706807329543 08:45:47 INFO - PROCESS | 1663 | |3;mootools.html;1357.5927221833447;1533.3636540835553;1543.336365362894;1752.7283271092986;1903.6573416784981;7902.644381002854;8386.16138386161;8677.956610216948;8920.197182253596;8923.018467968519;8745.825083498326;8830.86753698695;8840.557972101402;8851.380403371766;8873.68942087027;2333.7899589036983;2362.1024010875844;2365.4559451326204;2365.503244318695;2369.1471070414636;2307.734610519795;2344.765523447659;2362.83460157789;2364.267077206063;2365.656979737937;2323.256557901472;2349.8942547585316;2356.22244659262;2358.7641235876445;2364.645303204516;2300.2409204962323;2327.313442534448;2334.48641298914;2336.4742932840145;2337.4222399880014;2318.0843566791023;2321.1991862807304;2326.9993902622036;2331.044271848551;2333.8366314358;8823.602937867747;8883.156100170474;8893.066228046027;8897.243734282594;8897.421667591585;4776.546228108324;5198.454162312941;5201.687898726089;5311.070562651559;5362.195670649395;3303.6035675719068;3402.5406570112964;3552.2540266543797;3649.5073165122635;3654.1230104774663;883.4169448164245;1109.9056580190693;1110.2838669058492;1755.262789628359;1824.8448881845059;11569.24799888013;11585.826212606818;11684.47419866108;11699.707507312214;11719.238249513839;4316.6546676265825;4423.447069116361;4581.2440947243995;4591.380163677927;4594.517575654575;9119.81760364789;9126.813514243106;9129.132732390412;9134.269258459308;9144.085591440935;5187.170052791535;5195.220716892496;5250.264962905197;5253.842384728464;5267.552258058108;4759.762011899391;4837.685550439211;4907.214845624683;4920.5571498565305;4924.630652701062;3493.318802833423;3520.70074043704;3577.284543091392;3580.8925732228076;3586.3903136466865;1106.607154460155;1113.3987646670707;1118.5134466507095;1126.0372381613765;1132.6149109302876;781.6834182156235;793.4049462902823;897.7610896962151;925.8102089071724;960.3613596957945;5614.775408983676;5690.2887139107615;5851.356350801409;5964.373740757244;6139.355367686418;365.56315203331764;434.45258973693376;434.8021650149175;436.2017507960488;440.42304580998996;3761.6614504694708;3903.590123037097;3943.8225279862477;3999.920001599952;4023.597640235953;9151.542422878829;10183.643572474928;10542.631007914686;10747.408892510986;10879.292845965145;10800.297980631234;10811.729706757395;10822.134229261641;10822.971817677308;10827.566897324019;8015.599220038975;8113.432059755761;8142.715004974797;8251.174882511701;8260.876086858701;4922.852314430573;5657.519110875457;5669.036263835241;5733.283339582552;5767.134929760569;9732.805343893224;9963.452010139512;10051.597936082475;10088.445135517324;10195.898041019791;9610.087041731025;9959.551820167964;10172.694819155437;10309.639162629572;10370.11853992389;7458.067741532309;7540.1328847181085;7621.12357078943;7628.885566716614;7633.503822251536;8115.147909469657;8179.877301840596;8308.127646597262;8308.584570771316;8310.33517318601;7367.336939675455;7487.3261406473675;7568.281013303727;7572.4699271052705;7604.201558836464;7398.445116616168;7400.296971787671;7425.405967522477;7444.441666874898;7504.587247701429;6872.862542749217;6927.064846245692;7034.507584469038;7043.436525077879;7050.717971281091;4916.8770780730765;4931.580815630711;4941.901161976813;4990.476000019946;5060.49395060491;5020.196768517019;5020.397552293748;5046.116929537243;5121.564667003347;5126.846194614168 08:45:47 INFO - PROCESS | 1663 | |4;prototype.html;293.7312359191339;295.418026487819;302.0786600867354;310.2352700593038;310.74829388195565;4272.978635106821;5115.872103197422;5362;5363.946360536389;5389.218563308318;5002.774875130619;5141.485851414866;5205.843824685256;5246.632735708502;5277.36131934033;4063.94770640826;4064.166845796605;4091.5703851095654;4093.385992101179;4117.382392641113;3802.6957843372466;3958.8218530166214;4099.323611604097;4113.341865301552;4146.771927543984;3415.504751810986;4064.4106604542326;4077.694172937027;4096.91806163878;4119.526254480743;4033.7579745215235;4052.716309858311;4058.269511487931;4060.5736397678115;4104.15864747726;3185.8566364513654;3557.9110522236892;3567.464880267981;3595.496630471736;3608.2603066371594;4883.780229889663;5195.922061169085;5197.58419326453;5205.167173252262;5240.580753539708;4672.602828759559;4680.531946805327;4691.437027556715;4707.176244157259;4731.455882573514;5508.732991411957;5592.944070559283;5634.239377684001;5634.408387119375;5654.547636189095;3537.893863184083;3721.776693398405;3821.6942644588366;3825.2923209206106;3833.271678381099;5825.795527762919;5877.177195192677;5893.440123188333;5902.586818922677;5903.5572332074835;3858.9387918322122;3878.4957955466175;3892.2215556888737;3914.6672532835028;3938;3812.4853144825647;3920.843166273317;3927.113571524927;3947.6644485218503;3963.147923196527;3816.5610954740005;4368.344748287782;4544.227481328182;4545.545445455428;4625.306204069417;4862.513748625109;5596.636218645775;5597.720113994284;5628.662280263197;5710.400407957188;1177.3171560494893;1253.1979533098825;1280.8984273524761;1302.602706174607;1303.3222724183372;715.6815217228284;787.8384931089113;818.6479813680056;819.9422744659441;821.3183058061794;3752.3245815753426;4365.847195348148;4401.977990110029;4408.074304396048;4409.149935071396;4462.3935383262;4612.431773197129;4752.221983523107;4755.310479980383;4764.475907650156;3986.8405263789705;5279.656822306538;5338.3593968724;5343.385510666245;5363.81226657073;3022.2746540830044;3035.514317709156;3052.8015678980796;3067.4631939410515;3071.1247294520954;5093.516115968977;5660.518855897212;5922.615030023035;6004;6021.307549631849;4980.601551875841;5944.405559444021;5947.6431414115295;5952.375000624958;6062.393760623991;5258.7370631468275;6058.666773327421;6067.969660151671;6079.635221886701;6079.963212323921;4826.0589185108565;4866.464688884149;4884.535969082932;5568.468671115475;5915.822525324247;5363.383210930793;5585.301837270341;5807.883842323045;5850.7074646266665;5890.852728681817;4463.665225108195;5725.112607545837;6033.095035744674;6050.667213303311;6052.66710330936;3812.5424949006297;3867.5358956925347;3881.4954055972544;3893.3136136066023;3897.7271590988357;4986.601071914166;5813.418658134153;5823.592348535732;5854.443827836349;5863.677497737665;5250.453530037852;5634.718264086861;5652.349979752381;5676.7511147547475;5690.4677418548345;4710.7880145392855;5355.1431770918025;5594.580406469612;5643.858903527444;5649.322081350264;5101.51535604117;5222.582193424441;5382.273393092039;5536.819701125624;5582.441755824385;5237.119584228582;5465.808696695596;5596.804111856066;5687.687177205128;5693.686847223443;4651.465081515669;5551.694656793915;5552.333719953632;5555.50000499957;5557.666540007613 08:45:47 INFO - PROCESS | 1663 | |5;yui.html;685.6400389795353;700.7337211859494;709.2163159708522;764.3617579321267;814.9755507334783;15756;16017.11905845178;16020.75968860465;16025.278862451174;16041.438549650764;14395.640108997253;14404.207768572724;14411.135331880094;14418.279086045708;14705.779413308808;665.7203974330769;682.4983637026806;682.8361193313618;683.6991723641581;837.313403009534;688.7004153193352;691.9377256046955;835.8161204534993;854.4360721923532;858.7981824271292;892.9821403571957;900.6307413960278;905.9003509613938;907.0204179486173;908.0057337215771;839.7396806989792;874.8031692869117;901.2384535067857;901.7430032440789;902.8690839828254;519.2341296587541;533.8558589180939;534.0680512505663;663.4957432351448;663.9435647969929;12611.1802732823;12657.114002019862;12672.74654506914;14334.283285835667;14853.405863765436;14500.854991450162;14947.626309342244;14995.775063374058;15126.697466050728;15134.545963621109;11900.940495297467;11911.225770324987;12110.15228933975;12187.51249950001;12209.145359824815;3790.7346485746007;3875.1862108957152;3896.7272290939645;3932.0956180078456;4053.8783836484954;117411;120405.39797301045;120686;121676;123754.38122809328;11539.769204616028;11575.247608905394;11635.709107272385;11762.705932351588;12070.75858482843;22420.32739017832;24163.7583624165;24205.273841784776;24233;24237.75762242355;8104.230098140667;8139.75580732579;8140.715074972348;8186.0586032605825;8311.792205194797;4275.593818587229;4332.501762297376;4338.978305108454;4432.202203603382;4463.843765468193;3499.547687709623;3654;3670.0090975436483;3671.375866102769;3671.52270204877;669.8158006548239;675.4528831646383;688.0504903233506;688.50771698236;692.1279188222876;619.7180282971237;641.403494749887;643.9857224870847;699.552286536617;705.2242533213424;8405.159484051668;9487.620495180254;9615.182709469773;9623.518824058769;9668.323217374716;551.0241431673495;632.5730132160865;650.4666173737585;657.552864052449;678.633537889544;4504.527024662429;4648.83729069481;4649.418822647169;4651.976740116345;4668.159731248408;101603;126105.36947315205;147071;161245.19377403037;161809;104579.477102614;106017.93982060235;107221.4638926816;140048;145357;98751;102623;104058.47970760097;104260;106252.99999999846;8190.7133250335955;9674.419534827934;9881;9974.451405172786;9976.20190384753;39150.60849391584;41213;52981.67545811232;61121;61887;61901.690491547255;62526.06210906749;83938.16061839547;88152.11847881439;92091.53954230186;10762.246642734934;12909.806352904901;12939.223646581235;12955.935220323838;13160.078794484294;13285.870700990525;13664.385102670205;14249.28753562297;14349.923755718153;14462.638434038812;11590.304581725124;13463.394147263592;13683.589492315246;13746.518871839438;13832.031757776858;13473.932630336785;13480.393382298016;13725.107867988549;13844;13984.930075349965;11316.83024754613;13157.671058223621;13247.271400073088;13345.66635834112;13474.124181928144;11021.724456888642;11069.723256918642;11095.445227738743;11422.771544569228;11523.423828808358;11049.558017679203;11447.942760286478;11469.023043984125;11548.42257887119;11644 08:45:47 INFO - PROCESS | 1663 | __end_tp_report 08:45:47 INFO - PROCESS | 1663 | __start_cc_report 08:45:47 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1234 08:45:47 INFO - PROCESS | 1663 | __end_cc_report 08:45:47 INFO - PROCESS | 1663 | __startTimestamp1475509547836__endTimestamp 08:45:47 INFO - PROCESS | 1663 | 08:45:47 INFO - PROCESS | 1663 | ------- Summary: start ------- 08:45:47 INFO - PROCESS | 1663 | Number of tests: 6 08:45:47 INFO - PROCESS | 1663 | 08:45:47 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5677.03 Median:5894.79 stddev:638.26 (10.8%) stddev-sans-first:611.84 08:45:47 INFO - PROCESS | 1663 | Values: 3277.9 3279.3 3294.5 3297.4 3332.5 5533.4 5600.3 5602.7 5694.0 5742.1 5619.6 5696.3 5701.6 5714.1 5714.8 5100.5 5136.5 5147.3 5177.2 5196.8 5243.3 5250.8 5255.0 5261.4 5265.8 5127.3 5174.9 5183.9 5237.4 5248.3 5252.6 5261.1 5268.5 5269.8 5282.5 5064.0 5088.6 5151.2 5158.4 5202.4 5580.2 5601.1 5651.8 5663.3 5676.1 6188.1 6212.6 6216.7 6221.8 6233.2 4592.4 6144.1 6162.9 6248.5 6273.5 5714.5 5756.4 5760.5 5776.4 5832.0 6200.7 6204.3 6213.6 6215.0 6231.1 5928.8 5990.8 6016.9 6087.1 6109.9 6115.8 6132.9 6143.9 6151.5 6220.3 6195.7 6199.7 6202.7 6211.3 6212.5 6190.0 6197.8 6199.5 6204.3 6206.8 4765.8 4822.2 4827.1 4848.7 4874.4 4362.1 4376.9 4416.1 4424.4 4466.0 5740.7 5756.5 5760.9 5766.7 5780.0 6020.1 6052.0 6112.9 6141.9 6233.7 4714.6 5739.2 5753.0 5795.2 5805.3 5341.8 5350.2 5350.6 5367.5 5399.6 6175.1 6199.7 6200.3 6200.4 6209.5 6040.8 6125.0 6141.1 6144.1 6152.6 6172.9 6201.1 6206.9 6212.9 6218.9 6124.1 6131.8 6150.7 6151.4 6160.7 5352.1 6112.3 6143.5 6193.2 6257.2 5066.9 6043.2 6053.7 6101.7 6116.5 6033.6 6034.6 6037.1 6041.6 6044.3 5763.9 5813.8 5851.2 5894.8 5896.3 5994.0 6015.2 6017.8 6061.8 6124.6 5894.0 5895.9 5902.5 5909.3 5961.4 08:45:47 INFO - PROCESS | 1663 | 08:45:47 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:64083.54 Median:3960.79 stddev:136740.88 (3452.4%) stddev-sans-first:137041.08 08:45:47 INFO - PROCESS | 1663 | Values: 479.4 481.6 529.8 533.6 534.9 30394.2 34591.0 40235.4 41528.0 43608.3 42105.0 42529.4 42555.6 42610.4 42737.8 9618.9 9812.9 9814.4 9814.7 9825.1 9729.9 9858.3 9858.5 9863.4 9889.8 9781.7 9820.7 9822.9 9830.9 9868.2 9784.2 9844.6 9846.3 9850.4 9867.6 9527.3 9627.6 9630.8 9640.9 9641.4 40236.0 40410.4 40453.4 40482.8 40496.8 3538.5 3903.6 3958.4 3963.2 4060.6 30605.4 30799.4 31070.7 31528.2 31939.7 3158.1 3191.7 3209.9 3308.3 3339.7 369650.0 371354.1 373678.0 374019.0 375681.0 59891.0 60191.0 60268.0 60291.0 60313.7 63491.4 63673.0 63713.0 63718.4 63750.0 15720.4 15958.8 15995.0 16002.0 16047.7 12310.8 12340.4 12369.6 12388.9 12412.0 14032.2 15663.5 17105.9 17148.7 17219.2 6910.5 6931.9 6947.9 6970.4 7060.9 886.8 898.8 941.8 959.0 1058.5 28389.1 29185.9 29506.0 29591.4 29909.9 907.0 1032.2 1050.7 1070.4 1144.2 14674.5 15160.8 15260.8 15516.8 15596.1 626261.0 632665.0 632882.0 633313.0 634061.0 56731.0 56779.1 56867.1 56873.1 56878.0 1218.9 1254.6 1325.4 1357.4 1376.3 28760.9 29581.7 29621.4 30453.1 30525.7 41118.8 49999.8 50035.7 50057.5 50063.2 391103.3 519323.0 521863.0 522036.0 522240.0 25233.0 25514.0 25521.9 25537.0 25538.7 44680.0 44888.1 45180.1 45232.0 45609.1 37158.0 37709.4 37716.8 38428.0 38654.2 36854.6 36883.9 36930.8 37796.8 38077.8 36729.6 36972.4 37434.6 37473.3 37527.0 31497.2 33086.8 33232.8 33341.3 33410.5 22436.7 24495.3 24579.5 24747.9 24761.3 08:45:47 INFO - PROCESS | 1663 | 08:45:47 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:34586.37 Median:359.93 stddev:98735.30 (27432.1%) stddev-sans-first:98978.82 08:45:47 INFO - PROCESS | 1663 | Values: 350.5 369.4 370.2 377.1 402.4 14530.0 15035.9 19982.7 20694.6 21257.4 19472.3 20559.9 20573.5 20734.3 20958.9 5433.3 5852.4 6238.4 6240.2 6263.3 4134.7 4726.0 6086.7 6218.1 6819.4 6737.5 6801.6 6821.8 6829.1 6837.7 5793.8 6137.3 6139.3 6183.2 6815.6 6004.8 6005.1 6009.0 6014.6 6027.2 11996.8 12495.0 13189.8 13387.7 13449.3 2051.5 2246.9 2288.3 2458.7 2651.6 9914.5 9946.3 10110.4 10449.9 10498.6 1799.2 1877.7 1923.2 1929.8 1929.8 116061.0 117169.0 119574.4 121052.0 122069.6 9466.2 9801.2 9803.5 9952.9 10185.0 12878.7 13440.4 13457.7 13474.5 14225.2 8113.4 8204.8 8223.8 8241.3 8399.0 8142.6 8158.1 8164.1 8337.6 8339.4 4442.6 4477.3 4501.7 4594.4 4596.8 1845.3 1885.0 1891.7 1899.1 1903.2 823.5 878.2 974.4 1013.6 1068.3 19934.4 21541.5 21559.9 21573.4 21678.9 895.4 914.8 921.0 928.8 939.9 10533.5 10831.7 11340.5 11424.9 11441.4 579290.0 581192.0 588397.0 593747.0 605749.0 118781.0 130263.0 131399.0 138720.0 141890.0 946.6 996.2 997.4 997.5 1000.3 19132.4 19354.0 19459.3 19511.9 19543.6 54007.7 55780.7 56145.0 56685.7 56762.7 60174.7 74878.0 80335.6 83945.2 84067.6 6999.0 7395.6 7439.0 7952.0 8299.8 11959.6 12348.3 12648.9 13371.7 13410.6 11094.2 11321.5 11478.9 11516.6 11733.3 11188.0 11333.0 11449.9 11585.7 11627.7 9269.0 9551.6 11582.6 11637.4 11711.9 7998.8 8048.2 8362.2 8401.9 8419.2 10338.2 11203.8 11680.5 11686.2 11727.7 08:45:47 INFO - PROCESS | 1663 | 08:45:47 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5651.64 Median:4923.74 stddev:3196.44 (64.9%) stddev-sans-first:3189.12 08:45:47 INFO - PROCESS | 1663 | Values: 1357.6 1533.4 1543.3 1752.7 1903.7 7902.6 8386.2 8678.0 8920.2 8923.0 8745.8 8830.9 8840.6 8851.4 8873.7 2333.8 2362.1 2365.5 2365.5 2369.1 2307.7 2344.8 2362.8 2364.3 2365.7 2323.3 2349.9 2356.2 2358.8 2364.6 2300.2 2327.3 2334.5 2336.5 2337.4 2318.1 2321.2 2327.0 2331.0 2333.8 8823.6 8883.2 8893.1 8897.2 8897.4 4776.5 5198.5 5201.7 5311.1 5362.2 3303.6 3402.5 3552.3 3649.5 3654.1 883.4 1109.9 1110.3 1755.3 1824.8 11569.2 11585.8 11684.5 11699.7 11719.2 4316.7 4423.4 4581.2 4591.4 4594.5 9119.8 9126.8 9129.1 9134.3 9144.1 5187.2 5195.2 5250.3 5253.8 5267.6 4759.8 4837.7 4907.2 4920.6 4924.6 3493.3 3520.7 3577.3 3580.9 3586.4 1106.6 1113.4 1118.5 1126.0 1132.6 781.7 793.4 897.8 925.8 960.4 5614.8 5690.3 5851.4 5964.4 6139.4 365.6 434.5 434.8 436.2 440.4 3761.7 3903.6 3943.8 3999.9 4023.6 9151.5 10183.6 10542.6 10747.4 10879.3 10800.3 10811.7 10822.1 10823.0 10827.6 8015.6 8113.4 8142.7 8251.2 8260.9 4922.9 5657.5 5669.0 5733.3 5767.1 9732.8 9963.5 10051.6 10088.4 10195.9 9610.1 9959.6 10172.7 10309.6 10370.1 7458.1 7540.1 7621.1 7628.9 7633.5 8115.1 8179.9 8308.1 8308.6 8310.3 7367.3 7487.3 7568.3 7572.5 7604.2 7398.4 7400.3 7425.4 7444.4 7504.6 6872.9 6927.1 7034.5 7043.4 7050.7 4916.9 4931.6 4941.9 4990.5 5060.5 5020.2 5020.4 5046.1 5121.6 5126.8 08:45:47 INFO - PROCESS | 1663 | 08:45:47 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4523.54 Median:4983.60 stddev:1398.16 (28.1%) stddev-sans-first:1365.56 08:45:47 INFO - PROCESS | 1663 | Values: 293.7 295.4 302.1 310.2 310.7 4273.0 5115.9 5362.0 5363.9 5389.2 5002.8 5141.5 5205.8 5246.6 5277.4 4063.9 4064.2 4091.6 4093.4 4117.4 3802.7 3958.8 4099.3 4113.3 4146.8 3415.5 4064.4 4077.7 4096.9 4119.5 4033.8 4052.7 4058.3 4060.6 4104.2 3185.9 3557.9 3567.5 3595.5 3608.3 4883.8 5195.9 5197.6 5205.2 5240.6 4672.6 4680.5 4691.4 4707.2 4731.5 5508.7 5592.9 5634.2 5634.4 5654.5 3537.9 3721.8 3821.7 3825.3 3833.3 5825.8 5877.2 5893.4 5902.6 5903.6 3858.9 3878.5 3892.2 3914.7 3938.0 3812.5 3920.8 3927.1 3947.7 3963.1 3816.6 4368.3 4544.2 4545.5 4625.3 4862.5 5596.6 5597.7 5628.7 5710.4 1177.3 1253.2 1280.9 1302.6 1303.3 715.7 787.8 818.6 819.9 821.3 3752.3 4365.8 4402.0 4408.1 4409.1 4462.4 4612.4 4752.2 4755.3 4764.5 3986.8 5279.7 5338.4 5343.4 5363.8 3022.3 3035.5 3052.8 3067.5 3071.1 5093.5 5660.5 5922.6 6004.0 6021.3 4980.6 5944.4 5947.6 5952.4 6062.4 5258.7 6058.7 6068.0 6079.6 6080.0 4826.1 4866.5 4884.5 5568.5 5915.8 5363.4 5585.3 5807.9 5850.7 5890.9 4463.7 5725.1 6033.1 6050.7 6052.7 3812.5 3867.5 3881.5 3893.3 3897.7 4986.6 5813.4 5823.6 5854.4 5863.7 5250.5 5634.7 5652.3 5676.8 5690.5 4710.8 5355.1 5594.6 5643.9 5649.3 5101.5 5222.6 5382.3 5536.8 5582.4 5237.1 5465.8 5596.8 5687.7 5693.7 4651.5 5551.7 5552.3 5555.5 5557.7 08:45:47 INFO - PROCESS | 1663 | 08:45:47 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:23858.05 Median:3662.00 stddev:38023.73 (1038.3%) stddev-sans-first:38090.59 08:45:47 INFO - PROCESS | 1663 | Values: 685.6 700.7 709.2 764.4 815.0 15756.0 16017.1 16020.8 16025.3 16041.4 14395.6 14404.2 14411.1 14418.3 14705.8 665.7 682.5 682.8 683.7 837.3 688.7 691.9 835.8 854.4 858.8 893.0 900.6 905.9 907.0 908.0 839.7 874.8 901.2 901.7 902.9 519.2 533.9 534.1 663.5 663.9 12611.2 12657.1 12672.7 14334.3 14853.4 14500.9 14947.6 14995.8 15126.7 15134.5 11900.9 11911.2 12110.2 12187.5 12209.1 3790.7 3875.2 3896.7 3932.1 4053.9 117411.0 120405.4 120686.0 121676.0 123754.4 11539.8 11575.2 11635.7 11762.7 12070.8 22420.3 24163.8 24205.3 24233.0 24237.8 8104.2 8139.8 8140.7 8186.1 8311.8 4275.6 4332.5 4339.0 4432.2 4463.8 3499.5 3654.0 3670.0 3671.4 3671.5 669.8 675.5 688.1 688.5 692.1 619.7 641.4 644.0 699.6 705.2 8405.2 9487.6 9615.2 9623.5 9668.3 551.0 632.6 650.5 657.6 678.6 4504.5 4648.8 4649.4 4652.0 4668.2 101603.0 126105.4 147071.0 161245.2 161809.0 104579.5 106017.9 107221.5 140048.0 145357.0 98751.0 102623.0 104058.5 104260.0 106253.0 8190.7 9674.4 9881.0 9974.5 9976.2 39150.6 41213.0 52981.7 61121.0 61887.0 61901.7 62526.1 83938.2 88152.1 92091.5 10762.2 12909.8 12939.2 12955.9 13160.1 13285.9 13664.4 14249.3 14349.9 14462.6 11590.3 13463.4 13683.6 13746.5 13832.0 13473.9 13480.4 13725.1 13844.0 13984.9 11316.8 13157.7 13247.3 13345.7 13474.1 11021.7 11069.7 11095.4 11422.8 11523.4 11049.6 11447.9 11469.0 11548.4 11644.0 08:45:47 INFO - PROCESS | 1663 | -------- Summary: end -------- 08:45:47 INFO - PROCESS | 1663 | 08:45:48 INFO - TEST-INFO | 1663: exit 0 08:45:48 INFO - TEST-OK | dromaeo_css | took 1118642ms 08:45:48 INFO - TEST-START | kraken 08:45:48 INFO - operating with platform_type : mac_ 08:45:48 INFO - Initialising browser for kraken test... 08:45:48 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpgRCFgi/profile http://localhost:49272/getInfo.html) 08:45:50 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 08:45:50 INFO - PROCESS | 1671 | colorDepth:24 08:45:50 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 08:45:50 INFO - PROCESS | 1671 | __metrics 08:45:50 INFO - PROCESS | 1671 | 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" 08:45:50 INFO - PROCESS | 1671 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:45:51 INFO - TEST-INFO | 1671: exit 0 08:45:51 INFO - Browser initialized. 08:45:51 INFO - Running cycle 1/1 for kraken test... 08:45:51 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpgRCFgi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:46:03 INFO - PROCESS | 1672 | RSS: Main: 236437504 08:46:03 INFO - PROCESS | 1672 | 08:46:06 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 08:46:07 INFO - PROCESS | 1672 | RSS: Main: 294903808 08:46:07 INFO - PROCESS | 1672 | 08:46:09 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 08:46:10 INFO - PROCESS | 1672 | RSS: Main: 294461440 08:46:10 INFO - PROCESS | 1672 | 08:46:13 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 08:46:14 INFO - PROCESS | 1672 | RSS: Main: 383602688 08:46:14 INFO - PROCESS | 1672 | 08:46:16 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 08:46:17 INFO - PROCESS | 1672 | RSS: Main: 286396416 08:46:17 INFO - PROCESS | 1672 | 08:46:19 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:46:19 INFO - PROCESS | 1672 | RSS: Main: 290639872 08:46:19 INFO - PROCESS | 1672 | 08:46:22 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 08:46:23 INFO - PROCESS | 1672 | RSS: Main: 328454144 08:46:23 INFO - PROCESS | 1672 | 08:46:26 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 08:46:26 INFO - PROCESS | 1672 | RSS: Main: 329674752 08:46:26 INFO - PROCESS | 1672 | 08:46:29 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 08:46:30 INFO - PROCESS | 1672 | RSS: Main: 327802880 08:46:30 INFO - PROCESS | 1672 | 08:46:31 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:46:32 INFO - PROCESS | 1672 | RSS: Main: 304312320 08:46:32 INFO - PROCESS | 1672 | 08:46:34 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:46:34 INFO - PROCESS | 1672 | RSS: Main: 307011584 08:46:34 INFO - PROCESS | 1672 | 08:46:37 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:46:37 INFO - PROCESS | 1672 | RSS: Main: 304660480 08:46:37 INFO - PROCESS | 1672 | 08:46:40 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:46:41 INFO - PROCESS | 1672 | RSS: Main: 316170240 08:46:41 INFO - PROCESS | 1672 | 08:46:44 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:46:45 INFO - PROCESS | 1672 | RSS: Main: 318291968 08:46:45 INFO - PROCESS | 1672 | 08:46:46 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 08:46:47 INFO - PROCESS | 1672 | RSS: Main: 361598976 08:46:47 INFO - PROCESS | 1672 | 08:46:47 INFO - PROCESS | 1672 | __start_tp_report 08:46:47 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 08:46:47 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 08:46:47 INFO - PROCESS | 1672 | |i|pagename|runs| 08:46:47 INFO - PROCESS | 1672 | |0;ai-astar;114;114;114;114;114;115;114;112;112;114 08:46:47 INFO - PROCESS | 1672 | |1;audio-beat-detection;174;142;135;151;113;112;112;112;144;112 08:46:47 INFO - PROCESS | 1672 | |2;audio-dft;183;185;185;195;185;184;183;188;186;180 08:46:47 INFO - PROCESS | 1672 | |3;audio-fft;80;58;58;87;86;84;70;58;80;57 08:46:47 INFO - PROCESS | 1672 | |4;audio-oscillator;85;73;93;70;68;69;92;68;69;92 08:46:47 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;90;84;87;87;89;88;90;88;90;86 08:46:47 INFO - PROCESS | 1672 | |6;imaging-darkroom;94;94;94;94;94;99;145;124;94;94 08:46:47 INFO - PROCESS | 1672 | |7;imaging-desaturate;85;85;87;84;85;85;85;85;85;87 08:46:47 INFO - PROCESS | 1672 | |8;json-parse-financial;58;57;56;56;57;55;56;56;56;56 08:46:47 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;68;66;73;81;75;73;69;71;70;71 08:46:47 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;88;86;87;87;87;85;84;85;86;91 08:46:47 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;162;158;151;148;142;144;149;147;178;144 08:46:47 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;170;170;170;171;172;169;171;172;179;224 08:46:47 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;70;69;69;69;69;80;69;64;88;83 08:46:47 INFO - PROCESS | 1672 | __end_tp_report 08:46:47 INFO - PROCESS | 1672 | __start_cc_report 08:46:47 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1979 08:46:47 INFO - PROCESS | 1672 | __end_cc_report 08:46:47 INFO - PROCESS | 1672 | __startTimestamp1475509607177__endTimestamp 08:46:47 INFO - PROCESS | 1672 | 08:46:47 INFO - PROCESS | 1672 | ------- Summary: start ------- 08:46:47 INFO - PROCESS | 1672 | Number of tests: 14 08:46:47 INFO - PROCESS | 1672 | 08:46:47 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:0.95 (0.8%) stddev-sans-first:1.00 08:46:47 INFO - PROCESS | 1672 | Values: 114.0 114.0 114.0 114.0 114.0 115.0 114.0 112.0 112.0 114.0 08:46:47 INFO - PROCESS | 1672 | 08:46:47 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:138.50 stddev:21.91 (15.8%) stddev-sans-first:16.73 08:46:47 INFO - PROCESS | 1672 | Values: 174.0 142.0 135.0 151.0 113.0 112.0 112.0 112.0 144.0 112.0 08:46:47 INFO - PROCESS | 1672 | 08:46:47 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:185.40 Median:185.00 stddev:3.98 (2.2%) stddev-sans-first:4.12 08:46:47 INFO - PROCESS | 1672 | Values: 183.0 185.0 185.0 195.0 185.0 184.0 183.0 188.0 186.0 180.0 08:46:47 INFO - PROCESS | 1672 | 08:46:47 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:71.80 Median:80.00 stddev:12.95 (16.2%) stddev-sans-first:13.39 08:46:47 INFO - PROCESS | 1672 | Values: 80.0 58.0 58.0 87.0 86.0 84.0 70.0 58.0 80.0 57.0 08:46:47 INFO - PROCESS | 1672 | 08:46:47 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:79.00 stddev:11.14 (14.1%) stddev-sans-first:11.52 08:46:47 INFO - PROCESS | 1672 | Values: 85.0 73.0 93.0 70.0 68.0 69.0 92.0 68.0 69.0 92.0 08:46:47 INFO - PROCESS | 1672 | 08:46:47 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:88.50 stddev:1.97 (2.2%) stddev-sans-first:1.94 08:46:47 INFO - PROCESS | 1672 | Values: 90.0 84.0 87.0 87.0 89.0 88.0 90.0 88.0 90.0 86.0 08:46:47 INFO - PROCESS | 1672 | 08:46:47 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:102.60 Median:94.00 stddev:17.60 (18.7%) stddev-sans-first:18.39 08:46:47 INFO - PROCESS | 1672 | Values: 94.0 94.0 94.0 94.0 94.0 99.0 145.0 124.0 94.0 94.0 08:46:47 INFO - PROCESS | 1672 | 08:46:47 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.00 stddev:0.95 (1.1%) stddev-sans-first:1.00 08:46:47 INFO - PROCESS | 1672 | Values: 85.0 85.0 87.0 84.0 85.0 85.0 85.0 85.0 85.0 87.0 08:46:47 INFO - PROCESS | 1672 | 08:46:47 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:56.30 Median:56.00 stddev:0.82 (1.5%) stddev-sans-first:0.60 08:46:47 INFO - PROCESS | 1672 | Values: 58.0 57.0 56.0 56.0 57.0 55.0 56.0 56.0 56.0 56.0 08:46:47 INFO - PROCESS | 1672 | 08:46:47 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:72.00 stddev:4.19 (5.8%) stddev-sans-first:4.23 08:46:47 INFO - PROCESS | 1672 | Values: 68.0 66.0 73.0 81.0 75.0 73.0 69.0 71.0 70.0 71.0 08:46:47 INFO - PROCESS | 1672 | 08:46:47 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:87.00 stddev:1.96 (2.2%) stddev-sans-first:2.01 08:46:47 INFO - PROCESS | 1672 | Values: 88.0 86.0 87.0 87.0 87.0 85.0 84.0 85.0 86.0 91.0 08:46:47 INFO - PROCESS | 1672 | 08:46:47 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:152.30 Median:150.00 stddev:11.01 (7.3%) stddev-sans-first:11.10 08:46:47 INFO - PROCESS | 1672 | Values: 162.0 158.0 151.0 148.0 142.0 144.0 149.0 147.0 178.0 144.0 08:46:47 INFO - PROCESS | 1672 | 08:46:47 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.80 Median:171.50 stddev:16.82 (9.8%) stddev-sans-first:17.66 08:46:47 INFO - PROCESS | 1672 | Values: 170.0 170.0 170.0 171.0 172.0 169.0 171.0 172.0 179.0 224.0 08:46:47 INFO - PROCESS | 1672 | 08:46:47 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.00 Median:69.50 stddev:7.77 (11.2%) stddev-sans-first:8.17 08:46:47 INFO - PROCESS | 1672 | Values: 70.0 69.0 69.0 69.0 69.0 80.0 69.0 64.0 88.0 83.0 08:46:47 INFO - PROCESS | 1672 | -------- Summary: end -------- 08:46:47 INFO - PROCESS | 1672 | 08:46:48 INFO - TEST-INFO | 1672: exit 0 08:46:48 INFO - TEST-OK | kraken | took 59591ms 08:46:48 INFO - SUITE-END | took 1178s 08:46:48 INFO - Completed test suite (00:19:38) 08:46:48 INFO - javascript benchmark 08:46:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3277.88527401541, 3279.3441311737656, 3294.5058241263823, 3297.373499035183, 3332.4834650629145, 5533.446655334465, 5600.299962504681, 5602.747876345564, 5693.9715301423485, 5742.138679198117, 5619.5785316101255, 5696.316442026952, 5701.57238207134, 5714.1428785682065, 5714.771409143629, 5100.464451232613, 5136.537711605954, 5147.279380886679, 5177.197534382177, 5196.818111366084, 5243.2659427680155, 5250.789968401259, 5254.973725131388, 5261.368635763713, 5265.815696450625, 5127.33344665193, 5174.948250517485, 5183.896322073556, 5237.423883372845, 5248.29148065012, 5252.6323157379, 5261.053010458116, 5268.47315268472, 5269.762860671279, 5282.498162674539, 5063.987202559503, 5088.567471764904, 5151.150060240056, 5158.40678321994, 5202.4277329493725, 5580.190872323531, 5601.131824567198, 5651.8304450866535, 5663.2637757091725, 5676.063449530822, 6188.10272510488, 6212.565120441571, 6216.658083805389, 6221.782237621706, 6233.189685340922, 4592.357070010201, 6144.0783882417545, 6162.921488739453, 6248.531360147962, 6273.529485288622, 5714.51426628737, 5756.424357564294, 5760.481556659921, 5776.364599894008, 5832.037713777264, 6200.658963756947, 6204.348543402878, 6213.565050446516, 6214.9689251554355, 6231.127642130105, 5928.762849486058, 5990.820275391745, 6016.939830601726, 6087.147799308101, 6109.908351374733, 6115.848865095035, 6132.8756461173125, 6143.894099062211, 6151.507879369639, 6220.315765265817, 6195.721192546346, 6199.721012554446, 6202.72087756052, 6211.254649442096, 6212.534059945522, 6190.0405437157315, 6197.81406557804, 6199.535034872402, 6204.286507051656, 6206.844828879314, 4765.761711914391, 4822.228443449031, 4827.106985207678, 4848.7090774553635, 4874.390701162355, 4362.083962367875, 4376.8905777355185, 4416.138852923713, 4424.358468022118, 4465.977670111629, 5740.741666625012, 5756.510696590753, 5760.855978600569, 5766.711664416763, 5780.017397042513, 6020.066889632114, 6051.969740151271, 6112.908306375408, 6141.877162456726, 6233.688315584293, 4714.599259062949, 5739.2252045973255, 5752.9712351437975, 5795.1886735857015, 5805.303363596311, 5341.813036543703, 5350.224217488442, 5350.5987050971335, 5367.490088441592, 5399.6490228135835, 6175.104609831549, 6199.659018754011, 6200.348963358782, 6200.441960223601, 6209.472194863307, 6040.848978775566, 6125.021800333928, 6141.1484926909025, 6144.095330447575, 6152.64641778808, 6172.907406388818, 6201.131841542097, 6206.906896396648, 6212.9068063978175, 6218.875622487615, 6124.081387791689, 6131.785387511416, 6150.661713605616, 6151.446369826737, 6160.722767475385, 5352.063388906848, 6112.2970858351855, 6143.481172719164, 6193.194884665145, 6257.155284036597, 5066.923996139987, 6043.214382130295, 6053.667048312385, 6101.694915254131, 6116.480099191618, 6033.637981720935, 6034.63792172471, 6037.09443583466, 6041.607295525777, 6044.274687037584, 5763.8559036024435, 5813.82558523245, 5851.180834683233, 5894.793682221101, 5896.262967129109, 5993.951058564821, 6015.157877897185, 6017.819465416044, 6061.818145455643, 6124.571280010535, 5894.0274854648505, 5895.94104058954, 5902.468777810018, 5909.349971503218, 5961.433663801932], "value": 5636.95185971262, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.4151135614549, 481.56418441310615, 529.8119167695469, 533.578473006325, 534.9491798279162, 30394.240143996412, 34591.0, 40235.396469052954, 41528.0, 43608.3458748119, 42105.0, 42529.36205956913, 42555.57444425555, 42610.360844587354, 42737.78631106848, 9618.903810961889, 9812.901870981306, 9814.362066465692, 9814.65648702292, 9825.115739583436, 9729.902700973005, 9858.260630452745, 9858.507074646275, 9863.408195508293, 9889.802203955916, 9781.706548803511, 9820.705378838611, 9822.901770982271, 9830.901690983106, 9868.161206297438, 9784.217262618973, 9844.60621575136, 9846.261530385249, 9850.408975461496, 9867.60529578816, 9527.285453591008, 9627.56675949584, 9630.807383852354, 9640.855387169127, 9641.42151470914, 40235.994100147436, 40410.39384409236, 40453.393199102036, 40482.79758601217, 40496.79751601223, 3538.4692296155526, 3903.5901230370687, 3958.366661334173, 3963.207358528306, 4060.573639767841, 30605.38789224203, 30799.384012319853, 31070.689293107233, 31528.211794705087, 31939.6806031939, 3158.09994151668, 3191.7127458528844, 3209.9135561884727, 3308.3052558962645, 3339.716124129452, 369650.0, 371354.1432292821, 373678.0, 374019.0, 375681.0, 59891.0, 60191.0, 60268.0, 60291.0, 60313.69843150844, 63491.36508634947, 63673.0, 63713.0, 63718.36281637124, 63750.0, 15720.371185152466, 15958.808238352376, 15995.040297582182, 16002.0, 16047.679046419007, 12310.81533776994, 12340.444679989421, 12369.567065152676, 12388.876111238773, 12412.0, 14032.228227447384, 15663.530094097196, 17105.914470427568, 17148.742768858476, 17219.22513486896, 6910.48171387148, 6931.930680693229, 6947.89578156328, 6970.372666460043, 7060.929390706027, 886.7517095213351, 898.766320756595, 941.8304705153138, 958.990410095904, 1058.4919238765435, 28389.148325550268, 29185.854070729936, 29506.0, 29591.408171836443, 29909.850450747606, 906.9501177435304, 1032.2464600841279, 1050.7128767260199, 1070.3631339353071, 1144.2047776795048, 14674.486392976194, 15160.772588411182, 15260.847391526164, 15516.844831551765, 15596.142212178213, 626261.0, 632665.0, 632882.0, 633313.0, 634061.0, 56731.0, 56779.14831277534, 56867.14699279431, 56873.14690279649, 56878.0, 1218.939053047344, 1254.6361555149088, 1325.4035683942266, 1357.4095268558208, 1376.2636989210923, 28760.856195718887, 29581.704182957896, 29621.407571848013, 30453.086407407813, 30525.694743052285, 41118.79440602778, 49999.75000124976, 50035.74982125066, 50057.49942500674, 50063.24905126499, 391103.26690199337, 519323.0, 521863.0, 522036.0, 522240.0, 25233.0, 25514.0, 25521.872390637927, 25537.0, 25538.74461255438, 44680.0, 44888.10223795571, 45180.096398072514, 45232.0, 45609.087818242784, 37158.0, 37709.4343584841, 37716.81141594274, 38428.0, 38654.22691546097, 36854.63145368512, 36883.89348319555, 36930.81534592417, 37796.811015944746, 38077.809610951765, 36729.63270367262, 36972.44541331828, 37434.62565374311, 37473.2505349897, 37527.0, 31497.212569685023, 33086.76390064176, 33232.833835830665, 33341.33317333688, 33410.49884251689, 22436.66345004859, 24495.26380245447, 24579.508409832062, 24747.87626061858, 24761.304899872768], "value": 19482.65632091215, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.45679197244266, 369.3960374787223, 370.2262271851827, 377.09309111586634, 402.4265421773973, 14530.0, 15035.924820375896, 19982.7002594961, 20694.586108277825, 21257.362279131612, 19472.31846885359, 20559.8692071936, 20573.485662858435, 20734.274300399487, 20958.89520552395, 5433.320834895638, 5852.414758524134, 6238.4385405313515, 6240.188775459184, 6263.342349053352, 4134.710570260083, 4725.976370118162, 6086.665233412161, 6218.067289906505, 6819.420349270318, 6737.494687898403, 6801.591904485746, 6821.829454263659, 6829.078074459959, 6837.658117094125, 5793.768249270024, 6137.324894261627, 6139.293981192175, 6183.1961844960315, 6815.591064536119, 6004.819855404301, 6005.108390856866, 6009.0, 6014.578979471439, 6027.156198132265, 11996.820047699292, 12495.0, 13189.802152967713, 13387.665308367272, 13449.32753362328, 2051.497383141125, 2246.86518808872, 2288.2563166970635, 2458.7418321076207, 2651.6287719719253, 9914.454704991223, 9946.254030947708, 10110.39337639744, 10449.895501044968, 10498.580056797718, 1799.2443173867007, 1877.7371168036486, 1923.2397276987429, 1929.778075521319, 1929.778075521319, 116061.0, 117169.0, 119574.4021279888, 121052.0, 122069.63781338319, 9466.1953733932, 9801.166900813369, 9803.509824508747, 9952.850707239397, 10185.032421919906, 12878.742425151444, 13440.395182216824, 13457.663558411117, 13474.461021559027, 14225.217613031382, 8113.432059755761, 8204.753857384288, 8223.751618110813, 8241.299489543337, 8399.0, 8142.633581488847, 8158.143394943564, 8164.101948785629, 8337.583120843934, 8339.416240863082, 4442.622377097919, 4477.283634618446, 4501.662375321864, 4594.448666160015, 4596.770161491912, 1845.3449025595721, 1885.0386302985576, 1891.7446144770556, 1899.078946710844, 1903.181631898297, 823.5305875650942, 878.2466966061444, 974.3569244298728, 1013.5692330759398, 1068.2949253492814, 19934.401967940987, 21541.46146346354, 21559.892200538896, 21573.352799416043, 21678.89160554187, 895.4000819450983, 914.8124634449922, 921.0145144695111, 928.832810094176, 939.9425646148084, 10533.473326333806, 10831.67504974852, 11340.546378144782, 11424.885751142549, 11441.370724610226, 579290.0, 581192.0, 588397.0, 593747.0, 605749.0, 118781.0, 130263.0, 131399.0, 138720.0, 141890.0, 946.6024269806697, 996.1781530237438, 997.3666721631816, 997.4613708597421, 1000.3297790480395, 19132.426027219204, 19354.0, 19459.318923837596, 19511.902440487705, 19543.60912781765, 54007.72996134994, 55780.721096394256, 56145.0, 56685.71657141688, 56762.7161864188, 60174.699126504085, 74878.0, 80335.59832200801, 83945.16054839618, 84067.5796621013, 6998.950157476215, 7395.556266624019, 7438.962805185939, 7951.960240198762, 8299.792505187419, 11959.581414650445, 12348.259104453762, 12648.924841388218, 13371.665708357368, 13410.597682069554, 11094.223404361617, 11321.485498070904, 11478.885211148116, 11516.596919107791, 11733.296002239893, 11188.0, 11333.036691881283, 11449.885501145216, 11585.6524304271, 11627.6511704649, 9269.0, 9551.61793528279, 11582.594609188638, 11637.418129093343, 11711.941440292743, 7998.760037198894, 8048.195180481905, 8362.205590468895, 8401.915980840358, 8419.242268195892, 10338.172946164439, 11203.775924481628, 11680.47437865281, 11686.24039437434, 11727.706807329543], "value": 9400.567808227961, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.5927221833447, 1533.3636540835553, 1543.336365362894, 1752.7283271092986, 1903.6573416784981, 7902.644381002854, 8386.16138386161, 8677.956610216948, 8920.197182253596, 8923.018467968519, 8745.825083498326, 8830.86753698695, 8840.557972101402, 8851.380403371766, 8873.68942087027, 2333.7899589036983, 2362.1024010875844, 2365.4559451326204, 2365.503244318695, 2369.1471070414636, 2307.734610519795, 2344.765523447659, 2362.83460157789, 2364.267077206063, 2365.656979737937, 2323.256557901472, 2349.8942547585316, 2356.22244659262, 2358.7641235876445, 2364.645303204516, 2300.2409204962323, 2327.313442534448, 2334.48641298914, 2336.4742932840145, 2337.4222399880014, 2318.0843566791023, 2321.1991862807304, 2326.9993902622036, 2331.044271848551, 2333.8366314358, 8823.602937867747, 8883.156100170474, 8893.066228046027, 8897.243734282594, 8897.421667591585, 4776.546228108324, 5198.454162312941, 5201.687898726089, 5311.070562651559, 5362.195670649395, 3303.6035675719068, 3402.5406570112964, 3552.2540266543797, 3649.5073165122635, 3654.1230104774663, 883.4169448164245, 1109.9056580190693, 1110.2838669058492, 1755.262789628359, 1824.8448881845059, 11569.24799888013, 11585.826212606818, 11684.47419866108, 11699.707507312214, 11719.238249513839, 4316.6546676265825, 4423.447069116361, 4581.2440947243995, 4591.380163677927, 4594.517575654575, 9119.81760364789, 9126.813514243106, 9129.132732390412, 9134.269258459308, 9144.085591440935, 5187.170052791535, 5195.220716892496, 5250.264962905197, 5253.842384728464, 5267.552258058108, 4759.762011899391, 4837.685550439211, 4907.214845624683, 4920.5571498565305, 4924.630652701062, 3493.318802833423, 3520.70074043704, 3577.284543091392, 3580.8925732228076, 3586.3903136466865, 1106.607154460155, 1113.3987646670707, 1118.5134466507095, 1126.0372381613765, 1132.6149109302876, 781.6834182156235, 793.4049462902823, 897.7610896962151, 925.8102089071724, 960.3613596957945, 5614.775408983676, 5690.2887139107615, 5851.356350801409, 5964.373740757244, 6139.355367686418, 365.56315203331764, 434.45258973693376, 434.8021650149175, 436.2017507960488, 440.42304580998996, 3761.6614504694708, 3903.590123037097, 3943.8225279862477, 3999.920001599952, 4023.597640235953, 9151.542422878829, 10183.643572474928, 10542.631007914686, 10747.408892510986, 10879.292845965145, 10800.297980631234, 10811.729706757395, 10822.134229261641, 10822.971817677308, 10827.566897324019, 8015.599220038975, 8113.432059755761, 8142.715004974797, 8251.174882511701, 8260.876086858701, 4922.852314430573, 5657.519110875457, 5669.036263835241, 5733.283339582552, 5767.134929760569, 9732.805343893224, 9963.452010139512, 10051.597936082475, 10088.445135517324, 10195.898041019791, 9610.087041731025, 9959.551820167964, 10172.694819155437, 10309.639162629572, 10370.11853992389, 7458.067741532309, 7540.1328847181085, 7621.12357078943, 7628.885566716614, 7633.503822251536, 8115.147909469657, 8179.877301840596, 8308.127646597262, 8308.584570771316, 8310.33517318601, 7367.336939675455, 7487.3261406473675, 7568.281013303727, 7572.4699271052705, 7604.201558836464, 7398.445116616168, 7400.296971787671, 7425.405967522477, 7444.441666874898, 7504.587247701429, 6872.862542749217, 6927.064846245692, 7034.507584469038, 7043.436525077879, 7050.717971281091, 4916.8770780730765, 4931.580815630711, 4941.901161976813, 4990.476000019946, 5060.49395060491, 5020.196768517019, 5020.397552293748, 5046.116929537243, 5121.564667003347, 5126.846194614168], "value": 4460.869225942443, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.7312359191339, 295.418026487819, 302.0786600867354, 310.2352700593038, 310.74829388195565, 4272.978635106821, 5115.872103197422, 5362.0, 5363.946360536389, 5389.218563308318, 5002.774875130619, 5141.485851414866, 5205.843824685256, 5246.632735708502, 5277.36131934033, 4063.94770640826, 4064.166845796605, 4091.5703851095654, 4093.385992101179, 4117.382392641113, 3802.6957843372466, 3958.8218530166214, 4099.323611604097, 4113.341865301552, 4146.771927543984, 3415.504751810986, 4064.4106604542326, 4077.694172937027, 4096.91806163878, 4119.526254480743, 4033.7579745215235, 4052.716309858311, 4058.269511487931, 4060.5736397678115, 4104.15864747726, 3185.8566364513654, 3557.9110522236892, 3567.464880267981, 3595.496630471736, 3608.2603066371594, 4883.780229889663, 5195.922061169085, 5197.58419326453, 5205.167173252262, 5240.580753539708, 4672.602828759559, 4680.531946805327, 4691.437027556715, 4707.176244157259, 4731.455882573514, 5508.732991411957, 5592.944070559283, 5634.239377684001, 5634.408387119375, 5654.547636189095, 3537.893863184083, 3721.776693398405, 3821.6942644588366, 3825.2923209206106, 3833.271678381099, 5825.795527762919, 5877.177195192677, 5893.440123188333, 5902.586818922677, 5903.5572332074835, 3858.9387918322122, 3878.4957955466175, 3892.2215556888737, 3914.6672532835028, 3938.0, 3812.4853144825647, 3920.843166273317, 3927.113571524927, 3947.6644485218503, 3963.147923196527, 3816.5610954740005, 4368.344748287782, 4544.227481328182, 4545.545445455428, 4625.306204069417, 4862.513748625109, 5596.636218645775, 5597.720113994284, 5628.662280263197, 5710.400407957188, 1177.3171560494893, 1253.1979533098825, 1280.8984273524761, 1302.602706174607, 1303.3222724183372, 715.6815217228284, 787.8384931089113, 818.6479813680056, 819.9422744659441, 821.3183058061794, 3752.3245815753426, 4365.847195348148, 4401.977990110029, 4408.074304396048, 4409.149935071396, 4462.3935383262, 4612.431773197129, 4752.221983523107, 4755.310479980383, 4764.475907650156, 3986.8405263789705, 5279.656822306538, 5338.3593968724, 5343.385510666245, 5363.81226657073, 3022.2746540830044, 3035.514317709156, 3052.8015678980796, 3067.4631939410515, 3071.1247294520954, 5093.516115968977, 5660.518855897212, 5922.615030023035, 6004.0, 6021.307549631849, 4980.601551875841, 5944.405559444021, 5947.6431414115295, 5952.375000624958, 6062.393760623991, 5258.7370631468275, 6058.666773327421, 6067.969660151671, 6079.635221886701, 6079.963212323921, 4826.0589185108565, 4866.464688884149, 4884.535969082932, 5568.468671115475, 5915.822525324247, 5363.383210930793, 5585.301837270341, 5807.883842323045, 5850.7074646266665, 5890.852728681817, 4463.665225108195, 5725.112607545837, 6033.095035744674, 6050.667213303311, 6052.66710330936, 3812.5424949006297, 3867.5358956925347, 3881.4954055972544, 3893.3136136066023, 3897.7271590988357, 4986.601071914166, 5813.418658134153, 5823.592348535732, 5854.443827836349, 5863.677497737665, 5250.453530037852, 5634.718264086861, 5652.349979752381, 5676.7511147547475, 5690.4677418548345, 4710.7880145392855, 5355.1431770918025, 5594.580406469612, 5643.858903527444, 5649.322081350264, 5101.51535604117, 5222.582193424441, 5382.273393092039, 5536.819701125624, 5582.441755824385, 5237.119584228582, 5465.808696695596, 5596.804111856066, 5687.687177205128, 5693.686847223443, 4651.465081515669, 5551.694656793915, 5552.333719953632, 5555.50000499957, 5557.666540007613], "value": 4068.091066645822, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.6400389795353, 700.7337211859494, 709.2163159708522, 764.3617579321267, 814.9755507334783, 15756.0, 16017.11905845178, 16020.75968860465, 16025.278862451174, 16041.438549650764, 14395.640108997253, 14404.207768572724, 14411.135331880094, 14418.279086045708, 14705.779413308808, 665.7203974330769, 682.4983637026806, 682.8361193313618, 683.6991723641581, 837.313403009534, 688.7004153193352, 691.9377256046955, 835.8161204534993, 854.4360721923532, 858.7981824271292, 892.9821403571957, 900.6307413960278, 905.9003509613938, 907.0204179486173, 908.0057337215771, 839.7396806989792, 874.8031692869117, 901.2384535067857, 901.7430032440789, 902.8690839828254, 519.2341296587541, 533.8558589180939, 534.0680512505663, 663.4957432351448, 663.9435647969929, 12611.1802732823, 12657.114002019862, 12672.74654506914, 14334.283285835667, 14853.405863765436, 14500.854991450162, 14947.626309342244, 14995.775063374058, 15126.697466050728, 15134.545963621109, 11900.940495297467, 11911.225770324987, 12110.15228933975, 12187.51249950001, 12209.145359824815, 3790.7346485746007, 3875.1862108957152, 3896.7272290939645, 3932.0956180078456, 4053.8783836484954, 117411.0, 120405.39797301045, 120686.0, 121676.0, 123754.38122809328, 11539.769204616028, 11575.247608905394, 11635.709107272385, 11762.705932351588, 12070.75858482843, 22420.32739017832, 24163.7583624165, 24205.273841784776, 24233.0, 24237.75762242355, 8104.230098140667, 8139.75580732579, 8140.715074972348, 8186.0586032605825, 8311.792205194797, 4275.593818587229, 4332.501762297376, 4338.978305108454, 4432.202203603382, 4463.843765468193, 3499.547687709623, 3654.0, 3670.0090975436483, 3671.375866102769, 3671.52270204877, 669.8158006548239, 675.4528831646383, 688.0504903233506, 688.50771698236, 692.1279188222876, 619.7180282971237, 641.403494749887, 643.9857224870847, 699.552286536617, 705.2242533213424, 8405.159484051668, 9487.620495180254, 9615.182709469773, 9623.518824058769, 9668.323217374716, 551.0241431673495, 632.5730132160865, 650.4666173737585, 657.552864052449, 678.633537889544, 4504.527024662429, 4648.83729069481, 4649.418822647169, 4651.976740116345, 4668.159731248408, 101603.0, 126105.36947315205, 147071.0, 161245.19377403037, 161809.0, 104579.477102614, 106017.93982060235, 107221.4638926816, 140048.0, 145357.0, 98751.0, 102623.0, 104058.47970760097, 104260.0, 106252.99999999846, 8190.7133250335955, 9674.419534827934, 9881.0, 9974.451405172786, 9976.20190384753, 39150.60849391584, 41213.0, 52981.67545811232, 61121.0, 61887.0, 61901.690491547255, 62526.06210906749, 83938.16061839547, 88152.11847881439, 92091.53954230186, 10762.246642734934, 12909.806352904901, 12939.223646581235, 12955.935220323838, 13160.078794484294, 13285.870700990525, 13664.385102670205, 14249.28753562297, 14349.923755718153, 14462.638434038812, 11590.304581725124, 13463.394147263592, 13683.589492315246, 13746.518871839438, 13832.031757776858, 13473.932630336785, 13480.393382298016, 13725.107867988549, 13844.0, 13984.930075349965, 11316.83024754613, 13157.671058223621, 13247.271400073088, 13345.66635834112, 13474.124181928144, 11021.724456888642, 11069.723256918642, 11095.445227738743, 11422.771544569228, 11523.423828808358, 11049.558017679203, 11447.942760286478, 11469.023043984125, 11548.42257887119, 11644.0], "value": 7694.9659955795105, "unit": "score"}], "name": "dromaeo_css", "value": 7241.328984653454, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 115.0, 114.0, 112.0, 112.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 142.0, 135.0, 151.0, 113.0, 112.0, 112.0, 112.0, 144.0, 112.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 185.0, 195.0, 185.0, 184.0, 183.0, 188.0, 186.0, 180.0], "value": 185.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 58.0, 87.0, 86.0, 84.0, 70.0, 58.0, 80.0, 57.0], "value": 71.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 73.0, 93.0, 70.0, 68.0, 69.0, 92.0, 68.0, 69.0, 92.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 84.0, 87.0, 87.0, 89.0, 88.0, 90.0, 88.0, 90.0, 86.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 99.0, 145.0, 124.0, 94.0, 94.0], "value": 102.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 87.0, 84.0, 85.0, 85.0, 85.0, 85.0, 85.0, 87.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 56.0, 56.0, 57.0, 55.0, 56.0, 56.0, 56.0, 56.0], "value": 56.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 73.0, 81.0, 75.0, 73.0, 69.0, 71.0, 70.0, 71.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 87.0, 87.0, 85.0, 84.0, 85.0, 86.0, 91.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 158.0, 151.0, 148.0, 142.0, 144.0, 149.0, 147.0, 178.0, 144.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 170.0, 171.0, 172.0, 169.0, 171.0, 172.0, 179.0, 224.0], "value": 176.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 69.0, 69.0, 80.0, 69.0, 64.0, 88.0, 83.0], "value": 73.0, "unit": "score"}], "name": "kraken", "value": 1471.9999999999998, "alertThreshold": 2.0}]} 08:46:48 INFO - Return code: 0 08:46:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:46:48 INFO - # TBPL SUCCESS # 08:46:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:46:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:46:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:46:48 INFO - 2016-10-03 08:46:48.482 screenresolution[1676:11104] starting screenresolution argv=screenresolution get 08:46:48 INFO - 2016-10-03 08:46:48.485 screenresolution[1676:11104] Display 0: 1600x1200x32@60 08:46:48 INFO - 2016-10-03 08:46:48.492 screenresolution[1677:11106] starting screenresolution argv=screenresolution list 08:46:48 INFO - Available Modes on Display 0 08:46:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:46:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:46:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:46:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:46:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:46:48 INFO - Intel Iris: 08:46:48 INFO - Chipset Model: Intel Iris 08:46:48 INFO - Type: GPU 08:46:48 INFO - Bus: Built-In 08:46:48 INFO - VRAM (Dynamic, Max): 1536 MB 08:46:48 INFO - Vendor: Intel (0x8086) 08:46:48 INFO - Device ID: 0x0a2e 08:46:48 INFO - Revision ID: 0x0009 08:46:48 INFO - Displays: 08:46:48 INFO - Display: 08:46:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:46:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:46:48 INFO - Display Serial Number: 1600x1200 60 08:46:48 INFO - Main Display: Yes 08:46:48 INFO - Mirror: Off 08:46:48 INFO - Online: Yes 08:46:48 INFO - Rotation: Supported 08:46:48 INFO - Return code: 0 08:46:48 INFO - Running post-action listener: _resource_record_post_action 08:46:48 INFO - [mozharness: 2016-10-03 15:46:48.732147Z] Finished run-tests step (success) 08:46:48 INFO - Running post-run listener: _resource_record_post_run 08:46:48 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 33393664; Write bytes: 707924992; Read time: 316; Write time: 2356 08:46:48 INFO - TinderboxPrint: CPU usage
53.9% 08:46:48 INFO - TinderboxPrint: I/O read bytes / time
33,393,664 / 316 08:46:48 INFO - TinderboxPrint: I/O write bytes / time
707,924,992 / 2,356 08:46:48 INFO - TinderboxPrint: CPU idle
2,241.3 (46.1%) 08:46:48 INFO - TinderboxPrint: CPU system
979.2 (20.2%) 08:46:48 INFO - TinderboxPrint: CPU user
1,637.1 (33.7%) 08:46:48 INFO - TinderboxPrint: Swap in / out
437,059,584 / 0 08:46:48 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 408572416; Write bytes: 409892864; Read time: 30863; Write time: 747 08:46:48 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 29289472; Write bytes: 296426496; Read time: 284; Write time: 1603 08:46:49 INFO - Running post-run listener: _upload_blobber_files 08:46:49 INFO - Blob upload gear active. 08:46:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:46:49 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 08:46:49 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'] 08:46:49 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 08:46:49 INFO - (blobuploader) - INFO - Open directory for files ... 08:46:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:46:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:46:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:46:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:46:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:46:50 INFO - (blobuploader) - INFO - Done attempting. 08:46:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:46:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:46:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:46:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:46:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:46:51 INFO - (blobuploader) - INFO - Done attempting. 08:46:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:46:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:46:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:46:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:46:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:46:54 INFO - (blobuploader) - INFO - Done attempting. 08:46:54 INFO - (blobuploader) - INFO - Iteration through files over. 08:46:54 INFO - Return code: 0 08:46:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:46:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:46:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b61c658c2ba96885b7be3e25cd100d7d5dda82a31fdfe465be1fea480fe9a90d8a999337830191cab48aacbef7811622590b3f1f9e97e20df011c52fdf499d97", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d1c8f5ef9b316d957475b2df59c035f6cffe840f4baf76b767407fa66fd93a7ad5d72064aa77a0d877fa2d80a7774821f58023fb04f9829165bc3b689749f09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c797b9c5fd30767b12f0fcb4ffa4f182dd888eca6fc12ddfc9426bd350a2ee9484741a6a3fcc6057316ec76791a0a51252d6dd0d9c8d9b4c2dc054e976e75052"} 08:46:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:46:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:46:54 INFO - Contents: 08:46:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b61c658c2ba96885b7be3e25cd100d7d5dda82a31fdfe465be1fea480fe9a90d8a999337830191cab48aacbef7811622590b3f1f9e97e20df011c52fdf499d97", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d1c8f5ef9b316d957475b2df59c035f6cffe840f4baf76b767407fa66fd93a7ad5d72064aa77a0d877fa2d80a7774821f58023fb04f9829165bc3b689749f09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c797b9c5fd30767b12f0fcb4ffa4f182dd888eca6fc12ddfc9426bd350a2ee9484741a6a3fcc6057316ec76791a0a51252d6dd0d9c8d9b4c2dc054e976e75052"} 08:46:54 INFO - Running post-run listener: copy_logs_to_upload_dir 08:46:54 INFO - Copying logs to upload dir... 08:46:54 INFO - mkdir: /builds/slave/test/build/upload/logs 08:46:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.982254 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-03 08:46:54.286668) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 08:46:54.290241) ========= 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.GLltipmYjo/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.eTniLqILqn/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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/b61c658c2ba96885b7be3e25cd100d7d5dda82a31fdfe465be1fea480fe9a90d8a999337830191cab48aacbef7811622590b3f1f9e97e20df011c52fdf499d97", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d1c8f5ef9b316d957475b2df59c035f6cffe840f4baf76b767407fa66fd93a7ad5d72064aa77a0d877fa2d80a7774821f58023fb04f9829165bc3b689749f09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c797b9c5fd30767b12f0fcb4ffa4f182dd888eca6fc12ddfc9426bd350a2ee9484741a6a3fcc6057316ec76791a0a51252d6dd0d9c8d9b4c2dc054e976e75052"} build_url:https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011186 build_url: 'https://queue.taskcluster.net/v1/task/fL34yn7AQEG0276qTsRAUw/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/b61c658c2ba96885b7be3e25cd100d7d5dda82a31fdfe465be1fea480fe9a90d8a999337830191cab48aacbef7811622590b3f1f9e97e20df011c52fdf499d97", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d1c8f5ef9b316d957475b2df59c035f6cffe840f4baf76b767407fa66fd93a7ad5d72064aa77a0d877fa2d80a7774821f58023fb04f9829165bc3b689749f09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c797b9c5fd30767b12f0fcb4ffa4f182dd888eca6fc12ddfc9426bd350a2ee9484741a6a3fcc6057316ec76791a0a51252d6dd0d9c8d9b4c2dc054e976e75052"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 08:46:54.328274) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 08:46:54.329055) ========= 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.GLltipmYjo/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.eTniLqILqn/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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.005117 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 08:46:54.381598) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-03 08:46:54.381948) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-03 08:46:54.382265) ========= ========= Total master_lag: 0.18 =========