builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0315 starttime: 1467637020.39 results: success (0) buildid: 20160704040925 builduid: 5fbaead67d254a87b0527c730db6fa7b revision: 4191df8e4b8e1e2dd0593132dac25915f987f797 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 05:57:00.391329) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 05:57:00.391868) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 05:57:00.392212) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 05:57:00.416545) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 05:57:00.416989) ========= 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.yyuxdDYowj/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.EjDYaiewuf/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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-07-04 05:57:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 148M=0s 2016-07-04 05:57:00 (148 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.159757 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 05:57:00.600629) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 05:57:00.601017) ========= 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.yyuxdDYowj/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.EjDYaiewuf/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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.020386 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 05:57:00.652991) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 05:57:00.653856) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 4191df8e4b8e1e2dd0593132dac25915f987f797 --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 4191df8e4b8e1e2dd0593132dac25915f987f797 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yyuxdDYowj/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.EjDYaiewuf/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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-07-04 05:57:00,734 truncating revision to first 12 chars 2016-07-04 05:57:00,734 Setting DEBUG logging. 2016-07-04 05:57:00,735 attempt 1/10 2016-07-04 05:57:00,735 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4191df8e4b8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-04 05:57:01,415 unpacking tar archive at: fx-team-4191df8e4b8e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.939700 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 05:57:01.618154) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 05:57:01.618609) ========= script_repo_revision: 4191df8e4b8e1e2dd0593132dac25915f987f797 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 05:57:01.619244) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 05:57:01.619945) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 05:57:01.637114) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-04 05:57:01.637722) ========= /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.yyuxdDYowj/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.EjDYaiewuf/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 05:57:01 INFO - MultiFileLogger online at 20160704 05:57:01 in /builds/slave/test 05:57:01 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 05:57:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:57:01 INFO - {'append_to_log': False, 05:57:01 INFO - 'base_work_dir': '/builds/slave/test', 05:57:01 INFO - 'blob_upload_branch': 'Fx-Team', 05:57:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:57:01 INFO - 'branch': 'Fx-Team', 05:57:01 INFO - 'buildbot_json_path': 'buildprops.json', 05:57:01 INFO - 'config_files': ('talos/mac_config.py',), 05:57:01 INFO - 'default_actions': ('clobber', 05:57:01 INFO - 'read-buildbot-config', 05:57:01 INFO - 'download-and-extract', 05:57:01 INFO - 'populate-webroot', 05:57:01 INFO - 'create-virtualenv', 05:57:01 INFO - 'install', 05:57:01 INFO - 'run-tests'), 05:57:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:57:01 INFO - 'download_minidump_stackwalk': True, 05:57:01 INFO - 'download_symbols': 'ondemand', 05:57:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:57:01 INFO - 'tooltool.py': '/tools/tooltool.py', 05:57:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:57:01 INFO - '/tools/misc-python/virtualenv.py')}, 05:57:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:57:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:57:01 INFO - 'installer_path': 'installer.exe', 05:57:01 INFO - 'log_level': 'info', 05:57:01 INFO - 'log_name': 'talos', 05:57:01 INFO - 'log_to_console': True, 05:57:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:57:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:57:01 INFO - 'opt_config_files': (), 05:57:01 INFO - 'pip_index': False, 05:57:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:57:01 INFO - 'cmd': ('bash', 05:57:01 INFO - '-c', 05:57:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:57:01 INFO - 'enabled': True, 05:57:01 INFO - 'halt_on_failure': False, 05:57:01 INFO - 'name': 'check_screen_resolution'},), 05:57:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:57:01 INFO - 'cmd': ('bash', 05:57:01 INFO - '-c', 05:57:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:57:01 INFO - 'enabled': True, 05:57:01 INFO - 'halt_on_failure': False, 05:57:01 INFO - 'name': 'check_screen_resolution'},), 05:57:01 INFO - 'run_cmd_checks_enabled': True, 05:57:01 INFO - 'sps_profile': False, 05:57:01 INFO - 'sps_profile_interval': 0, 05:57:01 INFO - 'suite': 'dromaeojs', 05:57:01 INFO - 'system_bits': '32', 05:57:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:57:01 INFO - 'title': 't-yosemite-r7-0315', 05:57:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:57:01 INFO - 'use_talos_json': True, 05:57:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:57:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:57:01 INFO - 'work_dir': 'build'} 05:57:01 INFO - ##### 05:57:01 INFO - ##### Running clobber step. 05:57:01 INFO - ##### 05:57:01 INFO - Running pre-action listener: _resource_record_pre_action 05:57:01 INFO - Running main action method: clobber 05:57:01 INFO - rmtree: /builds/slave/test/build 05:57:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:57:03 INFO - Running post-action listener: _resource_record_post_action 05:57:03 INFO - ##### 05:57:03 INFO - ##### Finished clobber step (success) 05:57:03 INFO - ##### 05:57:03 INFO - ##### 05:57:03 INFO - ##### Running read-buildbot-config step. 05:57:03 INFO - ##### 05:57:03 INFO - Running pre-action listener: _resource_record_pre_action 05:57:03 INFO - Running main action method: read_buildbot_config 05:57:03 INFO - Using buildbot properties: 05:57:03 INFO - { 05:57:03 INFO - "project": "", 05:57:03 INFO - "product": "firefox", 05:57:03 INFO - "script_repo_revision": "production", 05:57:03 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:57:03 INFO - "repository": "", 05:57:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:57:03 INFO - "buildid": "20160704040925", 05:57:03 INFO - "pgo_build": "False", 05:57:03 INFO - "basedir": "/builds/slave/test", 05:57:03 INFO - "buildnumber": 62, 05:57:03 INFO - "slavename": "t-yosemite-r7-0315", 05:57:03 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:57:03 INFO - "platform": "yosemite_r7", 05:57:03 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:57:03 INFO - "branch": "fx-team", 05:57:03 INFO - "repo_path": "integration/fx-team", 05:57:03 INFO - "revision": "4191df8e4b8e1e2dd0593132dac25915f987f797", 05:57:03 INFO - "stage_platform": "macosx64", 05:57:03 INFO - "builduid": "5fbaead67d254a87b0527c730db6fa7b", 05:57:03 INFO - "slavebuilddir": "test" 05:57:03 INFO - } 05:57:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 05:57:03 INFO - Running post-action listener: _resource_record_post_action 05:57:03 INFO - ##### 05:57:03 INFO - ##### Finished read-buildbot-config step (success) 05:57:03 INFO - ##### 05:57:03 INFO - ##### 05:57:03 INFO - ##### Running download-and-extract step. 05:57:03 INFO - ##### 05:57:03 INFO - Running pre-action listener: _resource_record_pre_action 05:57:03 INFO - Running main action method: download_and_extract 05:57:03 INFO - mkdir: /builds/slave/test/build/tests 05:57:03 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:03 INFO - https://queue.taskcluster.net/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:57:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:57:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:57:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:57:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 05:57:04 INFO - Downloaded 1199 bytes. 05:57:04 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:57:04 INFO - Using the following test package requirements: 05:57:04 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:57:04 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:57:04 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 05:57:04 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:57:04 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 05:57:04 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:57:04 INFO - u'jsshell-mac.zip'], 05:57:04 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:57:04 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 05:57:04 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:57:04 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:57:04 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 05:57:04 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:57:04 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 05:57:04 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:57:04 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 05:57:04 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:57:04 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 05:57:04 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:04 INFO - https://queue.taskcluster.net/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:57:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:57:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:57:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:57:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:57:05 INFO - Downloaded 18795135 bytes. 05:57:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:57:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:57:08 INFO - Return code: 0 05:57:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:57:08 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:08 INFO - https://queue.taskcluster.net/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:57:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:57:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:57:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:57:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:57:09 INFO - Downloaded 18795135 bytes. 05:57:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:57:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:57:13 INFO - Return code: 0 05:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:13 INFO - https://queue.taskcluster.net/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:57:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:57:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:57:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:57:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:57:15 INFO - Downloaded 14403333 bytes. 05:57:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:57:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:57:15 INFO - Return code: 0 05:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:15 INFO - https://queue.taskcluster.net/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:57:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:57:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:57:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 05:57:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 05:57:18 INFO - Downloaded 112414594 bytes. 05:57:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:57:18 INFO - mkdir: /builds/slave/test/properties 05:57:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:57:18 INFO - Writing to file /builds/slave/test/properties/build_url 05:57:18 INFO - Contents: 05:57:18 INFO - build_url:https://queue.taskcluster.net/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:57:19 INFO - Running post-action listener: _resource_record_post_action 05:57:19 INFO - Running post-action listener: set_extra_try_arguments 05:57:19 INFO - ##### 05:57:19 INFO - ##### Finished download-and-extract step (success) 05:57:19 INFO - ##### 05:57:19 INFO - ##### 05:57:19 INFO - ##### Running populate-webroot step. 05:57:19 INFO - ##### 05:57:19 INFO - Running pre-action listener: _resource_record_pre_action 05:57:19 INFO - Running main action method: populate_webroot 05:57:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:57:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:57:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:57:19 INFO - '--tppagecycles', 05:57:20 INFO - '7'], 05:57:20 INFO - 'tests': ['tsvgm']}}, 05:57:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:57:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:57:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:57:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:57:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:57:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:57:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:20 INFO - 'talos_options': ['--disable-e10s'], 05:57:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:20 INFO - 'talos_options': ['--disable-e10s'], 05:57:20 INFO - 'tests': ['damp', 'tps']}, 05:57:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:20 INFO - 'tests': ['damp', 'tps']}, 05:57:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:57:20 INFO - 'tests': ['dromaeo_dom']}, 05:57:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:57:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:57:20 INFO - 'tests': ['basic_compositor_video']}, 05:57:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:57:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:57:20 INFO - 'tests': ['a11yr', 05:57:20 INFO - 'ts_paint', 05:57:20 INFO - 'tpaint', 05:57:20 INFO - 'sessionrestore', 05:57:20 INFO - 'sessionrestore_no_auto_restore', 05:57:20 INFO - 'tabpaint']}, 05:57:20 INFO - 'other-e10s': {'tests': ['a11yr', 05:57:20 INFO - 'ts_paint', 05:57:20 INFO - 'tpaint', 05:57:20 INFO - 'sessionrestore', 05:57:20 INFO - 'sessionrestore_no_auto_restore', 05:57:20 INFO - 'tabpaint']}, 05:57:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:57:20 INFO - 'ts_paint', 05:57:20 INFO - 'tpaint', 05:57:20 INFO - 'sessionrestore', 05:57:20 INFO - 'sessionrestore_no_auto_restore', 05:57:20 INFO - 'tabpaint']}, 05:57:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:57:20 INFO - 'ts_paint', 05:57:20 INFO - 'tpaint', 05:57:20 INFO - 'sessionrestore', 05:57:20 INFO - 'sessionrestore_no_auto_restore', 05:57:20 INFO - 'tabpaint']}, 05:57:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:57:20 INFO - 'tests': ['a11yr', 05:57:20 INFO - 'ts_paint', 05:57:20 INFO - 'tpaint', 05:57:20 INFO - 'sessionrestore', 05:57:20 INFO - 'sessionrestore_no_auto_restore', 05:57:20 INFO - 'tabpaint']}, 05:57:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:57:20 INFO - 'tests': ['a11yr', 05:57:20 INFO - 'ts_paint', 05:57:20 INFO - 'tpaint', 05:57:20 INFO - 'sessionrestore', 05:57:20 INFO - 'sessionrestore_no_auto_restore', 05:57:20 INFO - 'tabpaint']}, 05:57:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:57:20 INFO - 'tests': ['tsvgx', 05:57:20 INFO - 'tsvgr_opacity', 05:57:20 INFO - 'tart', 05:57:20 INFO - 'tscrollx', 05:57:20 INFO - 'cart']}, 05:57:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:57:20 INFO - 'tsvgr_opacity', 05:57:20 INFO - 'tart', 05:57:20 INFO - 'tscrollx', 05:57:20 INFO - 'cart']}, 05:57:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:20 INFO - 'talos_options': ['--disable-e10s'], 05:57:20 INFO - 'tests': ['tp5o']}, 05:57:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:20 INFO - 'tests': ['tp5o']}, 05:57:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:20 INFO - 'talos_options': ['--disable-e10s', 05:57:20 INFO - '--xperf_path', 05:57:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:20 INFO - 'tests': ['tp5n']}, 05:57:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:20 INFO - 'talos_options': ['--xperf_path', 05:57:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:20 INFO - 'tests': ['tp5n']}}, 05:57:20 INFO - 'talos.zip': {'path': '', 05:57:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:57:20 INFO - Running post-action listener: _resource_record_post_action 05:57:20 INFO - ##### 05:57:20 INFO - ##### Finished populate-webroot step (success) 05:57:20 INFO - ##### 05:57:20 INFO - ##### 05:57:20 INFO - ##### Running create-virtualenv step. 05:57:20 INFO - ##### 05:57:20 INFO - Running pre-action listener: _resource_record_pre_action 05:57:20 INFO - Running main action method: create_virtualenv 05:57:20 INFO - Creating virtualenv /builds/slave/test/build/venv 05:57:20 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 05:57:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:57:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:57:20 INFO - Using real prefix '/tools/python27' 05:57:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:57:20 INFO - Installing distribute.............................................................................................................................................................................................done. 05:57:23 INFO - Installing pip.................done. 05:57:23 INFO - Return code: 0 05:57:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:57: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:23 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 0x10ebb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2bbc77c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb18d30>, '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 0x10e87ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb10b20>, '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.yyuxdDYowj/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.EjDYaiewuf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 05:57:23 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 05:57:23 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 05:57:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yyuxdDYowj/Render', 05:57:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:23 INFO - 'HOME': '/Users/cltbld', 05:57:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:23 INFO - 'LOGNAME': 'cltbld', 05:57:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:23 INFO - 'MOZ_NO_REMOTE': '1', 05:57:23 INFO - 'NO_EM_RESTART': '1', 05:57:23 INFO - 'PAGER': '/bin/cat', 05:57:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:23 INFO - 'PWD': '/builds/slave/test', 05:57:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:23 INFO - 'SHELL': '/bin/bash', 05:57:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EjDYaiewuf/Listeners', 05:57:23 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 05:57:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:23 INFO - 'USER': 'cltbld', 05:57:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:23 INFO - 'XPC_FLAGS': '0x0', 05:57:23 INFO - 'XPC_SERVICE_NAME': '0', 05:57:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:23 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 05:57:23 INFO - Cleaning up... 05:57:23 INFO - Return code: 0 05:57:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:57: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:23 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 0x10ebb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2bbc77c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb18d30>, '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 0x10e87ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb10b20>, '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.yyuxdDYowj/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.EjDYaiewuf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 05:57:23 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 05:57:23 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 05:57:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yyuxdDYowj/Render', 05:57:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:23 INFO - 'HOME': '/Users/cltbld', 05:57:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:23 INFO - 'LOGNAME': 'cltbld', 05:57:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:23 INFO - 'MOZ_NO_REMOTE': '1', 05:57:23 INFO - 'NO_EM_RESTART': '1', 05:57:23 INFO - 'PAGER': '/bin/cat', 05:57:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:23 INFO - 'PWD': '/builds/slave/test', 05:57:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:23 INFO - 'SHELL': '/bin/bash', 05:57:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EjDYaiewuf/Listeners', 05:57:23 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 05:57:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:23 INFO - 'USER': 'cltbld', 05:57:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:23 INFO - 'XPC_FLAGS': '0x0', 05:57:23 INFO - 'XPC_SERVICE_NAME': '0', 05:57:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:24 INFO - Downloading/unpacking psutil>=3.1.1 05:57:24 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 05:57:24 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 05:57:24 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 05:57:24 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 05:57:24 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 05:57:24 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 05:57:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:57:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:57:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:57:27 INFO - Installing collected packages: psutil 05:57:27 INFO - Running setup.py install for psutil 05:57:27 INFO - building 'psutil._psutil_osx' extension 05:57:27 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 05:57:27 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 05:57:27 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 05:57:27 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 05:57:27 INFO - building 'psutil._psutil_posix' extension 05:57:27 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 05:57:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:57:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:57:27 INFO - ^ 05:57:27 INFO - 1 warning generated. 05:57:27 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 05:57:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:57:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:57:28 INFO - Successfully installed psutil 05:57:28 INFO - Cleaning up... 05:57:28 INFO - Return code: 0 05:57:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:57: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:28 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 0x10ebb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2bbc77c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb18d30>, '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 0x10e87ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb10b20>, '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.yyuxdDYowj/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.EjDYaiewuf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 05:57:28 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 05:57:28 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 05:57:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yyuxdDYowj/Render', 05:57:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:28 INFO - 'HOME': '/Users/cltbld', 05:57:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:28 INFO - 'LOGNAME': 'cltbld', 05:57:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:28 INFO - 'MOZ_NO_REMOTE': '1', 05:57:28 INFO - 'NO_EM_RESTART': '1', 05:57:28 INFO - 'PAGER': '/bin/cat', 05:57:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:28 INFO - 'PWD': '/builds/slave/test', 05:57:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:28 INFO - 'SHELL': '/bin/bash', 05:57:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EjDYaiewuf/Listeners', 05:57:28 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 05:57:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:28 INFO - 'USER': 'cltbld', 05:57:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:28 INFO - 'XPC_FLAGS': '0x0', 05:57:28 INFO - 'XPC_SERVICE_NAME': '0', 05:57:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:57:28 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 05:57:28 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 05:57:28 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 05:57:28 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 05:57:28 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 05:57:28 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 05:57:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:57:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:57:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:57:31 INFO - Installing collected packages: mozsystemmonitor 05:57:31 INFO - Running setup.py install for mozsystemmonitor 05:57:31 INFO - Successfully installed mozsystemmonitor 05:57:31 INFO - Cleaning up... 05:57:31 INFO - Return code: 0 05:57:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:57:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:31 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 0x10ebb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2bbc77c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb18d30>, '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 0x10e87ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb10b20>, '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.yyuxdDYowj/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.EjDYaiewuf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 05:57:31 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 05:57:31 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 05:57:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yyuxdDYowj/Render', 05:57:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:31 INFO - 'HOME': '/Users/cltbld', 05:57:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:31 INFO - 'LOGNAME': 'cltbld', 05:57:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:31 INFO - 'MOZ_NO_REMOTE': '1', 05:57:31 INFO - 'NO_EM_RESTART': '1', 05:57:31 INFO - 'PAGER': '/bin/cat', 05:57:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:31 INFO - 'PWD': '/builds/slave/test', 05:57:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:31 INFO - 'SHELL': '/bin/bash', 05:57:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EjDYaiewuf/Listeners', 05:57:31 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 05:57:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:31 INFO - 'USER': 'cltbld', 05:57:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:31 INFO - 'XPC_FLAGS': '0x0', 05:57:31 INFO - 'XPC_SERVICE_NAME': '0', 05:57:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:31 INFO - Downloading/unpacking jsonschema==2.5.1 05:57:31 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 05:57:31 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 05:57:31 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 05:57:31 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 05:57:31 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 05:57:31 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 05:57:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:57:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:57:34 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 05:57:34 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 05:57:34 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 05:57:34 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 05:57:34 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 05:57:34 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 05:57:34 INFO - Downloading functools32-3.2.3-2.tar.gz 05:57:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:57:34 INFO - warning: no files found matching '*.txt' 05:57:34 INFO - no previously-included directories found matching 'build' 05:57:34 INFO - no previously-included directories found matching 'dist' 05:57:34 INFO - no previously-included directories found matching '.git*' 05:57:34 INFO - Installing collected packages: jsonschema, functools32 05:57:34 INFO - Running setup.py install for functools32 05:57:34 INFO - warning: no files found matching '*.txt' 05:57:34 INFO - no previously-included directories found matching 'build' 05:57:34 INFO - no previously-included directories found matching 'dist' 05:57:34 INFO - no previously-included directories found matching '.git*' 05:57:34 INFO - Successfully installed jsonschema functools32 05:57:34 INFO - Cleaning up... 05:57:34 INFO - Return code: 0 05:57:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:57:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:34 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 0x10ebb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2bbc77c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb18d30>, '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 0x10e87ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb10b20>, '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.yyuxdDYowj/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.EjDYaiewuf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 05:57:34 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 05:57:34 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 05:57:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yyuxdDYowj/Render', 05:57:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:34 INFO - 'HOME': '/Users/cltbld', 05:57:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:34 INFO - 'LOGNAME': 'cltbld', 05:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:34 INFO - 'MOZ_NO_REMOTE': '1', 05:57:34 INFO - 'NO_EM_RESTART': '1', 05:57:34 INFO - 'PAGER': '/bin/cat', 05:57:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:34 INFO - 'PWD': '/builds/slave/test', 05:57:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:34 INFO - 'SHELL': '/bin/bash', 05:57:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EjDYaiewuf/Listeners', 05:57:34 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 05:57:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:34 INFO - 'USER': 'cltbld', 05:57:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:34 INFO - 'XPC_FLAGS': '0x0', 05:57:34 INFO - 'XPC_SERVICE_NAME': '0', 05:57:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:35 INFO - Downloading/unpacking blobuploader==1.2.4 05:57:35 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 05:57:35 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 05:57:35 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 05:57:35 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 05:57:35 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 05:57:35 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 05:57:37 INFO - Downloading blobuploader-1.2.4.tar.gz 05:57:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:57:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:57:37 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 05:57:37 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 05:57:37 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 05:57:37 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 05:57:37 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 05:57:37 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 05:57:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:57:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:38 INFO - Downloading docopt-0.6.1.tar.gz 05:57:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:57:38 INFO - Installing collected packages: blobuploader, requests, docopt 05:57:38 INFO - Running setup.py install for blobuploader 05:57:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:57:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:57:39 INFO - Running setup.py install for requests 05:57:39 INFO - Running setup.py install for docopt 05:57:39 INFO - Successfully installed blobuploader requests docopt 05:57:39 INFO - Cleaning up... 05:57:39 INFO - Return code: 0 05:57:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:39 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 0x10ebb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2bbc77c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb18d30>, '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 0x10e87ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb10b20>, '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.yyuxdDYowj/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.EjDYaiewuf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 05:57:39 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 05:57:39 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 05:57:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yyuxdDYowj/Render', 05:57:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:39 INFO - 'HOME': '/Users/cltbld', 05:57:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:39 INFO - 'LOGNAME': 'cltbld', 05:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:39 INFO - 'MOZ_NO_REMOTE': '1', 05:57:39 INFO - 'NO_EM_RESTART': '1', 05:57:39 INFO - 'PAGER': '/bin/cat', 05:57:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:39 INFO - 'PWD': '/builds/slave/test', 05:57:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:39 INFO - 'SHELL': '/bin/bash', 05:57:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EjDYaiewuf/Listeners', 05:57:39 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 05:57:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:39 INFO - 'USER': 'cltbld', 05:57:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:39 INFO - 'XPC_FLAGS': '0x0', 05:57:39 INFO - 'XPC_SERVICE_NAME': '0', 05:57:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:57:40 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-T8NDEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:57:40 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-bXAaDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:57:40 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-PgX1vM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:57:40 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-vYJG5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:57:40 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-LNLOKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:57:40 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-logVS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:57:40 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-KSOmI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:57:40 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-LxxJcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:57:40 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-felWt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:57:40 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-ilMp_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:57:41 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-PkhX3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:57:41 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-eFyJge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:57:41 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-_2qw90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:57:41 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-LLmhTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:41 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-PUwC_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:57:41 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-TY9NB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:57:41 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-TvClAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:57:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:57:41 INFO - Running setup.py install for manifestparser 05:57:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:57:41 INFO - Running setup.py install for mozcrash 05:57:42 INFO - Running setup.py install for mozdebug 05:57:42 INFO - Running setup.py install for mozdevice 05:57:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Running setup.py install for mozfile 05:57:42 INFO - Running setup.py install for mozhttpd 05:57:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Running setup.py install for mozinfo 05:57:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Running setup.py install for mozInstall 05:57:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Running setup.py install for mozleak 05:57:43 INFO - Running setup.py install for mozlog 05:57:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:57:43 INFO - Running setup.py install for moznetwork 05:57:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:57:43 INFO - Running setup.py install for mozprocess 05:57:43 INFO - Running setup.py install for mozprofile 05:57:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:57:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:57:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:57:43 INFO - Running setup.py install for mozrunner 05:57:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:57:43 INFO - Running setup.py install for mozscreenshot 05:57:44 INFO - Running setup.py install for moztest 05:57:44 INFO - Running setup.py install for mozversion 05:57:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:57:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:57:44 INFO - Cleaning up... 05:57:44 INFO - Return code: 0 05:57:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:44 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 0x10ebb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2bbc77c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb18d30>, '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 0x10e87ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb10b20>, '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.yyuxdDYowj/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.EjDYaiewuf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 05:57:44 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 05:57:44 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 05:57:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yyuxdDYowj/Render', 05:57:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:44 INFO - 'HOME': '/Users/cltbld', 05:57:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:44 INFO - 'LOGNAME': 'cltbld', 05:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:44 INFO - 'MOZ_NO_REMOTE': '1', 05:57:44 INFO - 'NO_EM_RESTART': '1', 05:57:44 INFO - 'PAGER': '/bin/cat', 05:57:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:44 INFO - 'PWD': '/builds/slave/test', 05:57:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:44 INFO - 'SHELL': '/bin/bash', 05:57:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EjDYaiewuf/Listeners', 05:57:44 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 05:57:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:44 INFO - 'USER': 'cltbld', 05:57:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:44 INFO - 'XPC_FLAGS': '0x0', 05:57:44 INFO - 'XPC_SERVICE_NAME': '0', 05:57:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:57:44 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-qgaRHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:57:44 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)) 05:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:57:44 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-_Oiqp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:57:44 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)) 05:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:57:44 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-43Zj4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:57:44 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)) 05:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:57:44 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-RWvDkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:57:45 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)) 05:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:57:45 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-2VELcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:57:45 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)) 05:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:57:45 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-R2FeFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:57:45 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)) 05:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:57:45 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-_M0ea1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:57:45 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)) 05:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:57:45 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-x9ZMSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:57:45 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)) 05:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:57:45 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-1FM5rH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:57:45 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)) 05:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:57:45 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-89VuOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 05:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:57:45 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-CKeCS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:57:45 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)) 05:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:57:45 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-0ZIonA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 05:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:57:45 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-zCKA0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:57:46 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)) 05:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:57:46 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-7QRidA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:57:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 05:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:46 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-04O0gg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:46 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)) 05:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:57:46 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-IDJGBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:57:46 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)) 05:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:57:46 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-m0iNw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:57:46 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)) 05:57:46 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)) 05:57:46 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)) 05:57:46 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)) 05:57:46 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)) 05:57:46 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)) 05:57:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:57:46 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 05:57:46 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 05:57:46 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 05:57:46 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 05:57:46 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 05:57:46 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 05:57:48 INFO - Downloading blessings-1.6.tar.gz 05:57:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:57:49 INFO - Installing collected packages: blessings 05:57:49 INFO - Running setup.py install for blessings 05:57:49 INFO - Successfully installed blessings 05:57:49 INFO - Cleaning up... 05:57:49 INFO - Return code: 0 05:57:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:57:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:57:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:57:49 INFO - Reading from file tmpfile_stdout 05:57:49 INFO - Current package versions: 05:57:49 INFO - blessings == 1.6 05:57:49 INFO - blobuploader == 1.2.4 05:57:49 INFO - docopt == 0.6.1 05:57:49 INFO - functools32 == 3.2.3-2 05:57:49 INFO - jsonschema == 2.5.1 05:57:49 INFO - manifestparser == 1.1 05:57:49 INFO - mozInstall == 1.12 05:57:49 INFO - mozcrash == 0.17 05:57:49 INFO - mozdebug == 0.1 05:57:49 INFO - mozdevice == 0.48 05:57:49 INFO - mozfile == 1.2 05:57:49 INFO - mozhttpd == 0.7 05:57:49 INFO - mozinfo == 0.9 05:57:49 INFO - mozleak == 0.1 05:57:49 INFO - mozlog == 3.2 05:57:49 INFO - moznetwork == 0.27 05:57:49 INFO - mozprocess == 0.22 05:57:49 INFO - mozprofile == 0.28 05:57:49 INFO - mozrunner == 6.11 05:57:49 INFO - mozscreenshot == 0.1 05:57:49 INFO - mozsystemmonitor == 0.3 05:57:49 INFO - moztest == 0.7 05:57:49 INFO - mozversion == 1.4 05:57:49 INFO - psutil == 3.1.1 05:57:49 INFO - requests == 1.2.3 05:57:49 INFO - wsgiref == 0.1.2 05:57:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:49 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 0x10ebb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2bbc77c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb18d30>, '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 0x10e87ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb10b20>, '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.yyuxdDYowj/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.EjDYaiewuf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 05:57:49 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 05:57:49 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 05:57:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yyuxdDYowj/Render', 05:57:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:49 INFO - 'HOME': '/Users/cltbld', 05:57:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:49 INFO - 'LOGNAME': 'cltbld', 05:57:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:49 INFO - 'MOZ_NO_REMOTE': '1', 05:57:49 INFO - 'NO_EM_RESTART': '1', 05:57:49 INFO - 'PAGER': '/bin/cat', 05:57:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:49 INFO - 'PWD': '/builds/slave/test', 05:57:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:49 INFO - 'SHELL': '/bin/bash', 05:57:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EjDYaiewuf/Listeners', 05:57:49 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 05:57:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:49 INFO - 'USER': 'cltbld', 05:57:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:49 INFO - 'XPC_FLAGS': '0x0', 05:57:49 INFO - 'XPC_SERVICE_NAME': '0', 05:57:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:49 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)) 05:57:49 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)) 05:57:49 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)) 05:57:49 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)) 05:57:49 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)) 05:57:49 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)) 05:57:49 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)) 05:57:49 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)) 05:57:49 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)) 05:57:49 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)) 05:57:49 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)) 05:57:49 INFO - Cleaning up... 05:57:49 INFO - Return code: 0 05:57:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:49 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 0x10ebb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec49618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2bbc77c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb18d30>, '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 0x10e87ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb10b20>, '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.yyuxdDYowj/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.EjDYaiewuf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 05:57:49 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 05:57:49 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 05:57:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yyuxdDYowj/Render', 05:57:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:49 INFO - 'HOME': '/Users/cltbld', 05:57:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:49 INFO - 'LOGNAME': 'cltbld', 05:57:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:49 INFO - 'MOZ_NO_REMOTE': '1', 05:57:49 INFO - 'NO_EM_RESTART': '1', 05:57:49 INFO - 'PAGER': '/bin/cat', 05:57:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:49 INFO - 'PWD': '/builds/slave/test', 05:57:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:49 INFO - 'SHELL': '/bin/bash', 05:57:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EjDYaiewuf/Listeners', 05:57:49 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 05:57:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:49 INFO - 'USER': 'cltbld', 05:57:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:49 INFO - 'XPC_FLAGS': '0x0', 05:57:49 INFO - 'XPC_SERVICE_NAME': '0', 05:57:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:57:50 INFO - Cleaning up... 05:57:50 INFO - Return code: 0 05:57:50 INFO - Running post-action listener: _resource_record_post_action 05:57:50 INFO - Running post-action listener: _start_resource_monitoring 05:57:50 INFO - Starting resource monitoring. 05:57:50 INFO - ##### 05:57:50 INFO - ##### Finished create-virtualenv step (success) 05:57:50 INFO - ##### 05:57:50 INFO - ##### 05:57:50 INFO - ##### Running install step. 05:57:50 INFO - ##### 05:57:50 INFO - Running pre-action listener: _resource_record_pre_action 05:57:50 INFO - Running main action method: install 05:57:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:57:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:57:50 INFO - Reading from file tmpfile_stdout 05:57:50 INFO - Detecting whether we're running mozinstall >=1.0... 05:57:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:57:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:57:50 INFO - Reading from file tmpfile_stdout 05:57:50 INFO - Output received: 05:57:50 INFO - Usage: mozinstall [options] installer 05:57:50 INFO - Options: 05:57:50 INFO - -h, --help show this help message and exit 05:57:50 INFO - -d DEST, --destination=DEST 05:57:50 INFO - Directory to install application into. [default: 05:57:50 INFO - "/builds/slave/test"] 05:57:50 INFO - --app=APP Application being installed. [default: firefox] 05:57:50 INFO - mkdir: /builds/slave/test/build/application 05:57:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:57:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:58:25 INFO - Reading from file tmpfile_stdout 05:58:25 INFO - Output received: 05:58:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:58:25 INFO - Running post-action listener: _resource_record_post_action 05:58:25 INFO - ##### 05:58:25 INFO - ##### Finished install step (success) 05:58:25 INFO - ##### 05:58:25 INFO - ##### 05:58:25 INFO - ##### Running run-tests step. 05:58:25 INFO - ##### 05:58:25 INFO - Running pre-action listener: _resource_record_pre_action 05:58:25 INFO - Running main action method: run_tests 05:58:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:58:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:58:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:58:25 INFO - 2016-07-04 05:58:25.854 screenresolution[1928:20452] starting screenresolution argv=screenresolution get 05:58:25 INFO - 2016-07-04 05:58:25.857 screenresolution[1928:20452] Display 0: 1600x1200x32@60 05:58:25 INFO - 2016-07-04 05:58:25.863 screenresolution[1929:20454] starting screenresolution argv=screenresolution list 05:58:25 INFO - Available Modes on Display 0 05:58:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:58:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:58:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:58:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:58:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:58:26 INFO - Intel Iris: 05:58:26 INFO - Chipset Model: Intel Iris 05:58:26 INFO - Type: GPU 05:58:26 INFO - Bus: Built-In 05:58:26 INFO - VRAM (Dynamic, Max): 1536 MB 05:58:26 INFO - Vendor: Intel (0x8086) 05:58:26 INFO - Device ID: 0x0a2e 05:58:26 INFO - Revision ID: 0x0009 05:58:26 INFO - Displays: 05:58:26 INFO - Display: 05:58:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:58:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:58:26 INFO - Display Serial Number: 1600x1200 60 05:58:26 INFO - Main Display: Yes 05:58:26 INFO - Mirror: Off 05:58:26 INFO - Online: Yes 05:58:26 INFO - Rotation: Supported 05:58:26 INFO - Return code: 0 05:58:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:58:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:58:26 INFO - Python 2.7.3 05:58:26 INFO - Return code: 0 05:58:26 INFO - grabbing minidump binary from tooltool 05:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:26 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 0x10eb18d30>, '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 0x10e87ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb10b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:58:26 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 05:58:26 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 05:58:26 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 05:58:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:58:26 INFO - Return code: 0 05:58:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:58:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:58:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:58:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:58:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:58:26 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/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0315', '--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 05:58:26 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/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0315 --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 05:58:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yyuxdDYowj/Render', 05:58:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:58:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:58:26 INFO - 'HOME': '/Users/cltbld', 05:58:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:58:26 INFO - 'LOGNAME': 'cltbld', 05:58:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:58:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:58:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:26 INFO - 'MOZ_NO_REMOTE': '1', 05:58:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:58:26 INFO - 'NO_EM_RESTART': '1', 05:58:26 INFO - 'PAGER': '/bin/cat', 05:58:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:58:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:26 INFO - 'PWD': '/builds/slave/test', 05:58:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:58:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:58:26 INFO - 'SHELL': '/bin/bash', 05:58:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EjDYaiewuf/Listeners', 05:58:26 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 05:58:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:58:26 INFO - 'USER': 'cltbld', 05:58:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:58:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:58:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:26 INFO - 'XPC_FLAGS': '0x0', 05:58:26 INFO - 'XPC_SERVICE_NAME': '0', 05:58:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:58:26 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/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0315', '--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 05:58:26 INFO - mozversion application_buildid: 20160704040925 05:58:26 INFO - mozversion application_changeset: 4191df8e4b8e1e2dd0593132dac25915f987f797 05:58:26 INFO - mozversion application_display_name: Nightly 05:58:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:58:26 INFO - mozversion application_name: Firefox 05:58:26 INFO - mozversion application_remotingname: firefox 05:58:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:58:26 INFO - mozversion application_vendor: Mozilla 05:58:26 INFO - mozversion application_version: 50.0a1 05:58:26 INFO - mozversion platform_buildid: 20160704040925 05:58:26 INFO - mozversion platform_changeset: 4191df8e4b8e1e2dd0593132dac25915f987f797 05:58:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:58:26 INFO - mozversion platform_version: 50.0a1 05:58:26 INFO - using testdate: 1467637106 05:58:26 INFO - actual date: 1467637106 05:58:26 INFO - starting webserver on 'localhost:52312' 05:58:26 INFO - SUITE-START | Running 2 tests 05:58:26 INFO - TEST-START | dromaeo_css 05:58:26 INFO - operating with platform_type : mac_ 05:58:26 INFO - Initialising browser for dromaeo_css test... 05:58:26 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/tmpMHX1EK/profile http://localhost:52312/getInfo.html) 05:58:28 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 05:58:28 INFO - PROCESS | 1939 | colorDepth:24 05:58:28 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 05:58:28 INFO - PROCESS | 1939 | __metrics 05:58:28 INFO - PROCESS | 1939 | 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" 05:58:28 INFO - PROCESS | 1939 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:58:28 INFO - PROCESS | 1939 | ************************* 05:58:28 INFO - PROCESS | 1939 | A coding exception was thrown in a Promise resolution callback. 05:58:28 INFO - PROCESS | 1939 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 05:58:28 INFO - PROCESS | 1939 | 05:58:28 ERROR - PROCESS | 1939 | Full message: TypeError: win is null 05:58:28 INFO - PROCESS | 1939 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:858:17 05:58:28 INFO - PROCESS | 1939 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 05:58:28 INFO - PROCESS | 1939 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 05:58:28 INFO - PROCESS | 1939 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 05:58:28 INFO - PROCESS | 1939 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 05:58:28 INFO - PROCESS | 1939 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 05:58:28 INFO - PROCESS | 1939 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 05:58:28 INFO - PROCESS | 1939 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 05:58:28 INFO - PROCESS | 1939 | 05:58:28 INFO - PROCESS | 1939 | ************************* 05:58:29 INFO - TEST-INFO | 1939: exit 0 05:58:29 INFO - Browser initialized. 05:58:29 INFO - Running cycle 1/1 for dromaeo_css test... 05:58:29 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/tmpMHX1EK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:58:41 INFO - PROCESS | 1941 | RSS: Main: 267575296 05:58:41 INFO - PROCESS | 1941 | 05:59:32 INFO - PROCESS | 1941 | 1467637172914 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] 06:01:43 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52312/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52312/tests/dromaeo/cssquery-ext.html) 06:01:44 INFO - PROCESS | 1941 | RSS: Main: 391368704 06:01:44 INFO - PROCESS | 1941 | 06:04:47 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52312/tests/dromaeo/cssquery-ext.html (next: http://localhost:52312/tests/dromaeo/cssquery-jquery.html) 06:04:48 INFO - PROCESS | 1941 | RSS: Main: 402112512 06:04:48 INFO - PROCESS | 1941 | 06:07:50 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52312/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52312/tests/dromaeo/cssquery-mootools.html) 06:07:51 INFO - PROCESS | 1941 | RSS: Main: 393646080 06:07:51 INFO - PROCESS | 1941 | 06:10:53 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52312/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52312/tests/dromaeo/cssquery-prototype.html) 06:10:54 INFO - PROCESS | 1941 | RSS: Main: 393945088 06:10:54 INFO - PROCESS | 1941 | 06:13:58 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52312/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52312/tests/dromaeo/cssquery-yui.html) 06:13:58 INFO - PROCESS | 1941 | RSS: Main: 363417600 06:13:58 INFO - PROCESS | 1941 | 06:17:02 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52312/tests/dromaeo/cssquery-yui.html (next: http://localhost:52312/tests/dromaeo/cssquery-dojo.html) 06:17:02 INFO - PROCESS | 1941 | RSS: Main: 439787520 06:17:02 INFO - PROCESS | 1941 | 06:17:02 INFO - PROCESS | 1941 | __start_tp_report 06:17:02 INFO - PROCESS | 1941 | _x_x_mozilla_page_load 06:17:02 INFO - PROCESS | 1941 | _x_x_mozilla_page_load_details 06:17:02 INFO - PROCESS | 1941 | |i|pagename|runs| 06:17:02 INFO - PROCESS | 1941 | |0;dojo.html;3298.7361011119115;3303.719183869371;3330.5337252784607;3342.7994320340767;3368;5649.6327738696955;5713.685747283897;5719.084946408576;5787.971060144699;5807.245058142441;5700.087985922253;5731.856703582413;5819.563532735041;5827.562932780037;5843.912341314884;5117.641765076446;5140.7172605506685;5185.870353241162;5186.636935414523;5327.866803329928;5296.967091417175;5316.0962636351915;5319.973400132994;5340.813071542497;5367.597430192732;4375.671824613167;5138.486151384869;5181.844544663666;5188.014277287303;5250.212468129773;5149.3305870237;5156.303898973628;5185.248139019859;5263.789448422059;5270.7628156733035;5160.045391602565;5171.534561889448;5188.6367954243215;5217.921731174036;5285.392179899322;5673.120666296731;5815.73829177688;5862.824115276549;5880.8235752927485;5887.676177810219;6180.47465965389;6209.192804935375;6255.155554000197;6260.436560709558;6330.87338253237;6274.184356033732;6305.117283580303;6311.6213027218055;6311.684415779193;6337.841553961188;5733.512651424592;5907.5864689471755;5944.227250457413;5958.970205148947;5959.433853783884;6207.534434917399;6245.812625621239;6254.74981000764;6285.245770507477;6336.334684858116;6260.530460215502;6261.467775239064;6265.749370025239;6273.843153921189;6279.0895320178315;6169.907451388232;6270.184875966186;6313.715882785285;6315;6342.5243106767175;6162.568620196633;6242.063690446469;6245.094461303176;6248.125262463259;6352.174217351715;6313.147725057151;6314.336994615591;6328.11406403104;6337.17616709834;6343;4907.484714105039;4966.404031516241;5018.197088465828;5041.445441001487;5098.566621837185;3872.4578559001197;4451.910961780811;4457.487388950248;4483;4563.817447302071;5865.853353666193;5872.148538461899;5886.440788125121;5898.292204935436;5961.6124951878855;6168;6205.193324867739;6241.78153764616;6316.9368306317265;6320.810375688737;5832.8541786454825;5906.704664766745;5917.171595976567;5918.674472903946;5964.224650795369;5507.311586051744;5515.951969125853;5525.861853453695;5533.391326954032;5533.723313834292;4799.448063472675;6208.068789681584;6227.84430389253;6251.374862513712;6351.237851457855;6257.56197066201;6275.309715931153;6285.937140628535;6294.307626161027;6302.936970630235;6340.017297318923;6342.365763423621;6346.555741098079;6351.269603995414;6355.586886852339;6126.540509461897;6146.385361463817;6182.103594978793;6202.131701561875;6241.188645476241;6264.467520260829;6305.495560355253;6333.1766870306565;6369.649669268235;6377.585456945284;6132.601380910227;6170.043643235305;6184;6185.288691800501;6188.164597779242;5056;6015.368386319374;6104.6713747925405;6123.862238009652;6220.098085777628;6056.071783054791;6064.302605200459;6076.574639775174;6094.1855883981;6136.539759517965;5835.358110607915;5999.760009599567;6214.130021797043;6214.720337584729;6227.221597300338;5911.527077833849;5999.130126131599;6027.547933905063;6032.819015429544;6112.572119951738 06:17:02 INFO - PROCESS | 1941 | |1;ext.html;477.6346095237144;490.7570752477523;525.4587774592171;535.5367607019926;553.2862607236665;30910.69089309106;35464.64535354645;44007.339889901676;44126.77936610317;46674.066518669606;45799.313010304875;46331.30503042457;46350.304745428846;46378.53621463785;46524.302135467995;8913;9833.360831545962;9999.950000250024;10006.749831254205;10021.348612340174;8868.955655221715;12088.21426607272;12281.447334869908;12302.876971230262;12546.372681365941;12285.877141228608;12357.629271121881;12368.567100151497;12395.194312369713;12435.751284974294;8926.86609700855;12139.57511487098;12224.877751222462;12290.754184916297;12546.1844980076;11854.229475084177;12201.816972745415;12266.32535210563;12270.631881043582;12279.877201227962;37520;43518.78240608808;43523.78238108821;43578.564214357764;43747.34378984318;3603.081214290339;3728.2170744143764;3755.286495565834;3861.6331448512346;4003.259397011562;33259;33261.833690831634;33595.31237501019;33919.6608033919;34450.483242751376;3243.659415761358;3280.3603297357026;3290.50642403639;3300.405926933151;3453.6546345365514;371248;371638;521481;525140.3742981261;525307;34594.48108278378;65571;66034;66133.37066405306;66277;34689.65310346915;36651.4502282466;36676.26647467036;36676.899693009254;36786.63213367885;14639.92680036593;14886.478973235884;14906.254687265593;14913.92543037278;14920.880933929782;10685.732856678489;11343.886561134448;11634.76730465386;12484.375781210903;12508.999280057573;7933.603319833986;7939.880901786478;7988.320992715568;7993.120756716757;8044.793281007778;3267.313864088568;3282.9343413131605;3285.589301337333;3288.4245257079915;3303;951.70497145885;962.6534447598859;969.3699095587924;980.4950450517989;1087.8259478483405;28773.712262877103;30337.089887303417;32010.839945800122;32100;32672;1090.9781804364027;1115.676453828397;1165.5687395663658;1167.6030149749126;1178.1458442629025;16722.331106755835;16735.91632041832;16771.161441927856;17026.978381297162;17038.57791225414;661021;687743;689210;692281.5385923139;694058;54119.72940135353;64130.67934660297;64975.70048599002;65044.02433963495;65110.67444662746;1272.7263638317806;1285.9164154330126;1396.6368744126396;1415.9646008849654;1422.7439060969125;32172.517412239304;33621;34002;34238.65761342454;34373.48439773455;56436.71781641065;57274;57540.136897945726;57540.42459575351;57572;616961;629401;630214;631241;632879;37896.43155352727;38830.805845970586;39038.219235615696;39944.00139996524;43271.350929736705;51127;51552;51989.220161696845;52255;52400.47599523956;41963.75453068366;42697.719068428;42795.57204427916;42837.5716242846;43674.56325436705;42108.94727631834;42450.78774606107;42453.08961096696;42849.57150428581;43301.783491082344;40136;40849.59150408458;41327.38008929929;41575.960600985214;41729.79135104305;33242.16894577558;35258.94223173309;36544.08639784026;37653.05867353338;37804.43293350547;21652.376071794362;25878.741212587633;26539.33651658724;26744.866275668497;27855.44289114247 06:17:02 INFO - PROCESS | 1941 | |2;jquery.html;346.2054584728049;367.49836473214066;375.5343374215973;376.60456520653327;392.239056230912;15874.365025398985;16258.918705406471;19992.90003549984;20297.695534566992;20353.796462035374;20527;20694.68957965632;21041.47396315089;21076.051577679;21613.891930540325;5248.658837175591;5782.653040817554;5991.520678345743;6043.063325184604;6053.061775424794;5963.910541341883;6046.3349031606485;6079.969600151993;6085;6087.54343424245;5872.119182122695;6024.7288872000645;6025.608335458203;6200.410960958701;6214.161088253096;5993.250843644544;6171.876562468748;6208.255009398856;6210.223722034746;6232.470240029603;5392.56859451243;5762.625429347121;5974.731137098841;6013.248343957005;6023.216981792383;13591.592252232449;13813.309334533233;20868.26961056356;26733.46533069347;26755;2190.5290362571964;2216.855904366227;2287.2109122352763;2289.98095847348;2370.9407264818346;9914.752131196705;10581.73545661357;14823.703525929528;14850.034747741362;15397.407045984188;1804.0528722420702;1822.9453116406528;1843.806400327973;1883.6421079994757;1963.5091227193202;117043;119859.4007029968;120047;120728;125530;11418.657440276806;11587.246828956091;11641;11675.532978680927;11852.88147118535;14312.212828294336;14366.99431039838;14425.567232983027;14426.639334016523;14440.277986100864;8407.915920840836;8439.57802109892;8469.576521173916;8490.915090849136;8582.055973842873;8399.118092600189;8404.663813447516;8414.11651776567;8522.147785221428;8524.36067294943;4202.453681021509;4275.717284814543;4556.681032327705;4565.56627120423;4571.954280457219;1876.240122750261;1918.1847714721187;1925.768907731053;1926.8554858385678;1950.3661310073999;728.7902082542953;861.8922634670666;876.8246350729879;909.7407238936871;1067.8184708599556;20403.795962040487;20782.58434831295;21667;21679.891600541898;21831.781682183293;946.2666433514082;948.307735353196;980.7548112971757;999.1756800639502;1035.7151783259512;11108.500117494732;11420.200585958903;11449.255798373079;11454.911783380565;11469.369184694922;589245;593593;608884;610119;610348;81593.18406815856;123744.14383784251;135813.6418635801;136220;139470.60529394576;1000.284796370584;1001.1840350114634;1032.4631191780231;1064.861567996171;1071.23406764164;17800.643987119925;18985.43043708691;19272;19311.613767724284;19339.323123690603;54693.453065468835;58158.418415816996;58708.706456467444;58724.706376467846;59119.70440147944;80383.79424308523;85372;86635;86805.13194868223;86905.69641455257;7233.63831808418;7462.776116716507;7573.697052117854;7774.067111946602;8339.20777526134;12318.75362492763;12324.383780811102;12411;13134.40895159701;14071.788923166365;9886.307958443127;11883.940580297043;12025.45885435175;12113.091518136;12210.75578488443;10791.298565593212;11641.708957276136;11841.23032002917;11872.52509899629;12171.33057681836;11907.880921190677;12083.456244469196;12175.512979480722;12208.32854193028;12266.815997759863;7538.6607602659105;7668.731594394169;7733.149353570991;7790.14308426084;7792.688292468238;11431.714207144887;11858.407079646156;11948.880511194777;12129.45417456199;12174.391280435766 06:17:02 INFO - PROCESS | 1941 | |3;mootools.html;1391.1931079973613;1523.2688309611385;1757.8505827004703;1791.0686443049622;1963.146031476307;8257.298129658979;8943;9132.771680707985;9141.862872056925;9178.632854685813;7976.401769867254;8188.976377952756;8736.51949142797;8957.059508751585;8981.68564100255;2375.1568193291437;2417.383567190364;2420.9757902420924;2421.9152329668464;2423.1761201191593;2361.268006917861;2368.7749663782;2404.1825779235055;2406.927792166229;2407.16952651335;2109.24067335759;2187.84685072045;2341.262502311775;2417.540667273221;2418.6130219164847;2405.194259922928;2420.5884999550117;2422.527607116613;2430.5260474207535;2431.9513609727883;2383.2611890314056;2418.286605451396;2421.8425802322795;2425.7331693513697;2427.5994460914103;9070.954645226797;9144.725658230263;9149.22231610314;9162.67141264513;9186.356955013152;5349.545288650469;5514.862128446781;5535.142052981794;5538.418466061046;5549.417311182308;3539.132912436444;3611.223586928823;3732.328180927432;3736.906577335561;3739.813009349549;1064.2124827627576;1074.5862842805577;1076.8923107689247;1086.6739978006567;1095.901368876797;12211.389430528527;12258.75482490354;12273.93863030688;12291.262524248485;12342.382880855921;4659.879754711598;4696.365990591226;4873.610111191097;4883.975580122076;4893.853184404474;10194.286399951932;10196.235282353704;10198.33710808795;10210.234232432596;10218.540165692562;5480.616356855062;5494.917576236359;5500.834974950758;5503.394626591072;5514.145307477347;4986.700797952134;5004.799808007711;5023.34696489454;5036.798528058836;5057.544820966131;3395.966040339565;3422.469517224834;3483.303339332144;3488.75578709493;3537.4163263061823;1275.7320962597958;1288.5683296095913;1299.5841330774251;1307.4704744578462;1314.677903913528;638.8530637953204;801.7514570483169;823.1603764160523;884.6284560484612;997.1275134257525;5862.70686465675;6068.2111325528285;6113.32753397126;6149.169862068564;6234.688265586702;958.3866325551653;960.6109525642127;973.6397532912725;1019.2457581389718;1023.918086553074;3977.801109944491;4124.443200167999;4138.151678905817;4184.393262976912;4185.204811085884;10689.946550267354;10963.890361096286;11056.834147487794;11074.39090850011;11093.722656933642;9934.950325248328;10400.531976061095;10851.67444976652;11051.115910727123;11052.060574851066;7575.204603516661;7773.689052437952;7776.416768742367;7853.568053757098;8263.967004124605;5226.529612334908;6034.336223015377;6248.593841400295;6274.121622972696;6285.402886725754;9768.902310976799;10020.248481364073;10332.276740628084;10346.22403319769;10351.63769268072;10334.948325258325;10441.008104230086;10478.580856765644;10491.580336786443;10493.2130090242;7201.4238860889955;8248.54632995191;8255.133211012997;8276.834463310579;8489.40574159803;8332.625031873702;8364.958175209085;8443.577821108796;8443.577821108796;8457.450265732708;6338.746450141943;7552.735654252075;7693.538387696756;7761.990941177611;7852.293293603603;6473.126127972663;7678.5392876427595;7710.4217183710325;7747.690092396241;7759.92240077592;7255.165655949633;7268.055152830098;7352.264773522605;7353.485256032026;7360.631968401665;4993.600511958962;5167.741612919414;5187.2478490619405;5244.4493328201515;5272.841814745564;4669.836555720533;5194.740262986911;5283.973580132229;5301.310829592128;5338.386085600206 06:17:02 INFO - PROCESS | 1941 | |4;prototype.html;272.4333386555964;298.29453342844175;306.04208826373457;309.23155957445755;313.59702781925233;4080.8777586163783;4146.9792651036705;4799.640026997971;5046.520580544847;5279.683219006853;4291.828326866922;5022.723750193738;5233.424323324441;5233.618703489912;5256.947430525694;3112.8443577821076;3434.6908778209954;3612.530371051773;3646.6171052039554;4082.8775136745944;3834.5015148030716;3876.108495046141;4053.952753859297;4098.938515922264;4110.1163249901265;2694.528457519936;3770.8491660333552;3865.8067096645195;3919.196564704229;4030.979845100785;3901.60983901609;3944;4066.79666016698;4069.4302797608357;4089.85685501009;2687.274435902315;2712.51174788538;3456.3927773585406;3522.947155792665;3552.875649352286;4663.802577680081;4905.3623029006;5072.492750724935;5125.564327032207;5159.7678104485385;3778.5465744110606;3831.961680383188;4594.977025114887;4637.582617564435;4658.604018658418;4788.880277993043;5051.87370315745;5367.194920761878;5463.699496527689;5538.0031594313;3579.3020361029608;3627.3833448313585;3926.607339266079;3928.8624898128714;3956.9819392109944;5150.944056468415;5304.329136322085;5400.270963419927;5790;5816.447437493389;3654.6162652921594;3872.6708229800784;3883.902902427462;3889.922201555953;3894.4811523505855;3767.547894252707;3787.7348585598743;3893.4159876018825;4005.5593884673244;4019.3167161582014;3821.5032045833864;4081.1837632473625;4611.677182597186;4624.537546245416;4637.976810115928;4650.51169627191;5275.63070585063;5542.334919809648;5551.833444996657;5599.972000140055;1167.9416029198505;1354.7290541891464;1374.4639590559689;1378.600205940266;1379.4895888521312;670.577536152221;744.8547533230968;843.3379796859435;845.6659619450282;858.4420126917554;3865.594112618191;3868.2843673920415;3948.4077388391397;4285.5928686774705;4304.440422745023;3892.7858967757047;4182.728122672017;4595.632349412039;4604.907901842012;4695.2252878275385;3816.961830381716;4148.585141485888;4275.679324050647;4288.957110428918;4312.439382880269;2019.424464027745;2392.413858604653;2492.9127480538455;2493.887775050127;2512.8869200886006;4200.413450111345;4543.795529201194;4711.104890070876;4818.807442613603;4856.73287969158;4310.349135281779;4364.476262848479;4444.4888837784065;4484.798184081724;4802.079168332695;4263.4031235627035;4520.570545798144;4692.906141877212;4822.42130944289;4862.319275301461;4235.915281694287;4328.636479508945;4381.342798580238;4637.791299391468;4653.115488227273;4539.568740969603;4637.791299391603;4668.649851261237;4682.274247491644;4691.648126390466;3875.825587848497;4099.713020088565;4525.275955847049;4899.559039686445;5044.974775126101;3621.60162382143;3871.806409679561;3946.881593552198;3964.0089977505622;3976.8608098716404;5050.292958985671;5693.3168019837885;5736.282964629421;5771.538276937751;5782.132680098019;4733.5266473352385;5101.7194054327365;5540.667559946416;5619.438056194348;5620.690862002629;5560.972195138998;5562.972185139049;5590.496855282881;5622.357491978763;5627.774889004394;5112.236278483917;5237.973810130925;5448.101063324507;5449.3188351456065;5476.58925580591;4887.535684110004;5351;5367.44344140195;5590;5595.216669666332;5459.262999495176;5495.2856128703015;5511.511891789275;5513.338399392099;5544.3346798384455 06:17:02 INFO - PROCESS | 1941 | |5;yui.html;652.5008368598023;748.5695724958149;790.735103740247;861.7414775567328;866.0516734176077;15222.162781047038;16495.505134845946;16518.83481165188;16544.503664890057;16545.91727041366;13263.336833158352;14807.85192148081;15012.699746005073;15031.699366012674;15032.473863414783;668.7926742709764;699.6361891816252;885.5483703311326;905.0180554098811;907.4600612635504;714.2607401339615;898.874157617934;901.0538934119181;906.3338446242007;906.5920335848862;944.4333399960003;958.089814676057;958.9424634521916;959.5777857742606;960.9903900961006;738.7931379213775;954.8185844689522;955.4553904274567;957.1816097236866;958.53990084759;552.9944700552983;554.4732504121101;554.7365001624237;561.3712641841123;692.4737199728193;15389.692206155925;15581.844181558152;15600.687986240326;15638.843611563967;15661;12102.213356131911;14356.784648230285;14724.55826325212;14731.263436828223;14765.7785133222;12344.5062197512;13261.602151935458;13536.661583460394;13782.93108534461;13818.65453363664;4530.365748795171;4653.906921861578;4659.906801863977;4788;4841.612670986347;125368.37315813453;125634;125837;126083.36958315241;126180.47639047168;11344.602938897098;11430;11568.537258509565;11924.593604081605;12194.451249693786;23263.651045234335;23816.523669526858;24621.753782462303;24638.753612463646;24685.136020239566;7501.5874126923545;8437.325013998865;8468.66125354979;8482.787930301669;8592.699255526026;4567.566081222279;4595.540445955445;4640.535946405333;4646.070785842845;4724.385829842098;3648.6533779290926;3772.188979369394;3776.546814382292;4035.8991025224605;4201.579842015774;740.870347689163;755.3730403764862;764.2548515197637;766.0118447203157;767.9232076792388;568.795233715862;606.6269244414741;665.5241502325861;671.70445004198;704.1409480433863;7822.843543129219;10378.792424151627;10394.532246048946;10411.427371494641;10582.523786429476;576.6828244465527;600.4845494136372;610.703808652803;647.8218489915216;666.1240468783534;4660.650451216105;4776.259679749644;4783.856484305476;4787.736674482937;4793.257045158006;92509;94557;117182;152862;153915;115218;120471;131004;135281;140679;95843;101734;102173.95652086768;102974;103183;9052;9086.182243598108;9099.36304458708;9139.543022848698;9307.813843723223;52632.94734105373;55607.88784224374;62576;63183.36816631959;64171.67914160586;92816;95519;98026;99167;101621.49189254007;13105.934470327587;13324.666883327995;13649;14187.432502699776;14303.284835757962;15086.396544138111;15189;15378.461753838563;15542.766858497358;15573.532794016197;14222.857771422154;14554.345054472362;14612.78080828767;14666.706665866835;14923.253837307873;14323;14362.497312594009;14582.416703331748;14714.55856324312;14784.334704938088;13642;14190.50333238373;14257.073290236101;14282.928585357007;14307.64230894236;10546.997408514633;10724.927507249213;10978.176636752116;11751.236169648946;12146.574869879512;11682.182247242952;11744.58893938708;11842;11857.703557411134;12022.519099235933 06:17:02 INFO - PROCESS | 1941 | __end_tp_report 06:17:02 INFO - PROCESS | 1941 | __start_cc_report 06:17:02 INFO - PROCESS | 1941 | _x_x_mozilla_cycle_collect,1325 06:17:02 INFO - PROCESS | 1941 | __end_cc_report 06:17:02 INFO - PROCESS | 1941 | __startTimestamp1467638222318__endTimestamp 06:17:02 INFO - PROCESS | 1941 | 06:17:02 INFO - PROCESS | 1941 | ------- Summary: start ------- 06:17:02 INFO - PROCESS | 1941 | Number of tests: 6 06:17:02 INFO - PROCESS | 1941 | 06:17:02 INFO - PROCESS | 1941 | [#0] dojo.html Cycles:165 Average:5782.25 Median:6027.55 stddev:670.45 (11.1%) stddev-sans-first:643.58 06:17:02 INFO - PROCESS | 1941 | Values: 3298.7 3303.7 3330.5 3342.8 3368.0 5649.6 5713.7 5719.1 5788.0 5807.2 5700.1 5731.9 5819.6 5827.6 5843.9 5117.6 5140.7 5185.9 5186.6 5327.9 5297.0 5316.1 5320.0 5340.8 5367.6 4375.7 5138.5 5181.8 5188.0 5250.2 5149.3 5156.3 5185.2 5263.8 5270.8 5160.0 5171.5 5188.6 5217.9 5285.4 5673.1 5815.7 5862.8 5880.8 5887.7 6180.5 6209.2 6255.2 6260.4 6330.9 6274.2 6305.1 6311.6 6311.7 6337.8 5733.5 5907.6 5944.2 5959.0 5959.4 6207.5 6245.8 6254.7 6285.2 6336.3 6260.5 6261.5 6265.7 6273.8 6279.1 6169.9 6270.2 6313.7 6315.0 6342.5 6162.6 6242.1 6245.1 6248.1 6352.2 6313.1 6314.3 6328.1 6337.2 6343.0 4907.5 4966.4 5018.2 5041.4 5098.6 3872.5 4451.9 4457.5 4483.0 4563.8 5865.9 5872.1 5886.4 5898.3 5961.6 6168.0 6205.2 6241.8 6316.9 6320.8 5832.9 5906.7 5917.2 5918.7 5964.2 5507.3 5516.0 5525.9 5533.4 5533.7 4799.4 6208.1 6227.8 6251.4 6351.2 6257.6 6275.3 6285.9 6294.3 6302.9 6340.0 6342.4 6346.6 6351.3 6355.6 6126.5 6146.4 6182.1 6202.1 6241.2 6264.5 6305.5 6333.2 6369.6 6377.6 6132.6 6170.0 6184.0 6185.3 6188.2 5056.0 6015.4 6104.7 6123.9 6220.1 6056.1 6064.3 6076.6 6094.2 6136.5 5835.4 5999.8 6214.1 6214.7 6227.2 5911.5 5999.1 6027.5 6032.8 6112.6 06:17:02 INFO - PROCESS | 1941 | 06:17:02 INFO - PROCESS | 1941 | [#1] ext.html Cycles:180 Average:72855.29 Median:20624.11 stddev:160892.56 (780.1%) stddev-sans-first:161252.13 06:17:02 INFO - PROCESS | 1941 | Values: 477.6 490.8 525.5 535.5 553.3 30910.7 35464.6 44007.3 44126.8 46674.1 45799.3 46331.3 46350.3 46378.5 46524.3 8913.0 9833.4 10000.0 10006.7 10021.3 8869.0 12088.2 12281.4 12302.9 12546.4 12285.9 12357.6 12368.6 12395.2 12435.8 8926.9 12139.6 12224.9 12290.8 12546.2 11854.2 12201.8 12266.3 12270.6 12279.9 37520.0 43518.8 43523.8 43578.6 43747.3 3603.1 3728.2 3755.3 3861.6 4003.3 33259.0 33261.8 33595.3 33919.7 34450.5 3243.7 3280.4 3290.5 3300.4 3453.7 371248.0 371638.0 521481.0 525140.4 525307.0 34594.5 65571.0 66034.0 66133.4 66277.0 34689.7 36651.5 36676.3 36676.9 36786.6 14639.9 14886.5 14906.3 14913.9 14920.9 10685.7 11343.9 11634.8 12484.4 12509.0 7933.6 7939.9 7988.3 7993.1 8044.8 3267.3 3282.9 3285.6 3288.4 3303.0 951.7 962.7 969.4 980.5 1087.8 28773.7 30337.1 32010.8 32100.0 32672.0 1091.0 1115.7 1165.6 1167.6 1178.1 16722.3 16735.9 16771.2 17027.0 17038.6 661021.0 687743.0 689210.0 692281.5 694058.0 54119.7 64130.7 64975.7 65044.0 65110.7 1272.7 1285.9 1396.6 1416.0 1422.7 32172.5 33621.0 34002.0 34238.7 34373.5 56436.7 57274.0 57540.1 57540.4 57572.0 616961.0 629401.0 630214.0 631241.0 632879.0 37896.4 38830.8 39038.2 39944.0 43271.4 51127.0 51552.0 51989.2 52255.0 52400.5 41963.8 42697.7 42795.6 42837.6 43674.6 42108.9 42450.8 42453.1 42849.6 43301.8 40136.0 40849.6 41327.4 41576.0 41729.8 33242.2 35258.9 36544.1 37653.1 37804.4 21652.4 25878.7 26539.3 26744.9 27855.4 06:17:02 INFO - PROCESS | 1941 | 06:17:02 INFO - PROCESS | 1941 | [#2] jquery.html Cycles:180 Average:35455.00 Median:21755.84 stddev:100630.79 (462.5%) stddev-sans-first:100878.56 06:17:02 INFO - PROCESS | 1941 | Values: 346.2 367.5 375.5 376.6 392.2 15874.4 16258.9 19992.9 20297.7 20353.8 20527.0 20694.7 21041.5 21076.1 21613.9 5248.7 5782.7 5991.5 6043.1 6053.1 5963.9 6046.3 6080.0 6085.0 6087.5 5872.1 6024.7 6025.6 6200.4 6214.2 5993.3 6171.9 6208.3 6210.2 6232.5 5392.6 5762.6 5974.7 6013.2 6023.2 13591.6 13813.3 20868.3 26733.5 26755.0 2190.5 2216.9 2287.2 2290.0 2370.9 9914.8 10581.7 14823.7 14850.0 15397.4 1804.1 1822.9 1843.8 1883.6 1963.5 117043.0 119859.4 120047.0 120728.0 125530.0 11418.7 11587.2 11641.0 11675.5 11852.9 14312.2 14367.0 14425.6 14426.6 14440.3 8407.9 8439.6 8469.6 8490.9 8582.1 8399.1 8404.7 8414.1 8522.1 8524.4 4202.5 4275.7 4556.7 4565.6 4572.0 1876.2 1918.2 1925.8 1926.9 1950.4 728.8 861.9 876.8 909.7 1067.8 20403.8 20782.6 21667.0 21679.9 21831.8 946.3 948.3 980.8 999.2 1035.7 11108.5 11420.2 11449.3 11454.9 11469.4 589245.0 593593.0 608884.0 610119.0 610348.0 81593.2 123744.1 135813.6 136220.0 139470.6 1000.3 1001.2 1032.5 1064.9 1071.2 17800.6 18985.4 19272.0 19311.6 19339.3 54693.5 58158.4 58708.7 58724.7 59119.7 80383.8 85372.0 86635.0 86805.1 86905.7 7233.6 7462.8 7573.7 7774.1 8339.2 12318.8 12324.4 12411.0 13134.4 14071.8 9886.3 11883.9 12025.5 12113.1 12210.8 10791.3 11641.7 11841.2 11872.5 12171.3 11907.9 12083.5 12175.5 12208.3 12266.8 7538.7 7668.7 7733.1 7790.1 7792.7 11431.7 11858.4 11948.9 12129.5 12174.4 06:17:02 INFO - PROCESS | 1941 | 06:17:02 INFO - PROCESS | 1941 | [#3] mootools.html Cycles:180 Average:5840.86 Median:4940.28 stddev:3291.57 (66.6%) stddev-sans-first:3283.82 06:17:02 INFO - PROCESS | 1941 | Values: 1391.2 1523.3 1757.9 1791.1 1963.1 8257.3 8943.0 9132.8 9141.9 9178.6 7976.4 8189.0 8736.5 8957.1 8981.7 2375.2 2417.4 2421.0 2421.9 2423.2 2361.3 2368.8 2404.2 2406.9 2407.2 2109.2 2187.8 2341.3 2417.5 2418.6 2405.2 2420.6 2422.5 2430.5 2432.0 2383.3 2418.3 2421.8 2425.7 2427.6 9071.0 9144.7 9149.2 9162.7 9186.4 5349.5 5514.9 5535.1 5538.4 5549.4 3539.1 3611.2 3732.3 3736.9 3739.8 1064.2 1074.6 1076.9 1086.7 1095.9 12211.4 12258.8 12273.9 12291.3 12342.4 4659.9 4696.4 4873.6 4884.0 4893.9 10194.3 10196.2 10198.3 10210.2 10218.5 5480.6 5494.9 5500.8 5503.4 5514.1 4986.7 5004.8 5023.3 5036.8 5057.5 3396.0 3422.5 3483.3 3488.8 3537.4 1275.7 1288.6 1299.6 1307.5 1314.7 638.9 801.8 823.2 884.6 997.1 5862.7 6068.2 6113.3 6149.2 6234.7 958.4 960.6 973.6 1019.2 1023.9 3977.8 4124.4 4138.2 4184.4 4185.2 10689.9 10963.9 11056.8 11074.4 11093.7 9935.0 10400.5 10851.7 11051.1 11052.1 7575.2 7773.7 7776.4 7853.6 8264.0 5226.5 6034.3 6248.6 6274.1 6285.4 9768.9 10020.2 10332.3 10346.2 10351.6 10334.9 10441.0 10478.6 10491.6 10493.2 7201.4 8248.5 8255.1 8276.8 8489.4 8332.6 8365.0 8443.6 8443.6 8457.5 6338.7 7552.7 7693.5 7762.0 7852.3 6473.1 7678.5 7710.4 7747.7 7759.9 7255.2 7268.1 7352.3 7353.5 7360.6 4993.6 5167.7 5187.2 5244.4 5272.8 4669.8 5194.7 5284.0 5301.3 5338.4 06:17:02 INFO - PROCESS | 1941 | 06:17:02 INFO - PROCESS | 1941 | [#4] prototype.html Cycles:180 Average:4185.98 Median:4522.92 stddev:1283.31 (28.4%) stddev-sans-first:1252.84 06:17:02 INFO - PROCESS | 1941 | Values: 272.4 298.3 306.0 309.2 313.6 4080.9 4147.0 4799.6 5046.5 5279.7 4291.8 5022.7 5233.4 5233.6 5256.9 3112.8 3434.7 3612.5 3646.6 4082.9 3834.5 3876.1 4054.0 4098.9 4110.1 2694.5 3770.8 3865.8 3919.2 4031.0 3901.6 3944.0 4066.8 4069.4 4089.9 2687.3 2712.5 3456.4 3522.9 3552.9 4663.8 4905.4 5072.5 5125.6 5159.8 3778.5 3832.0 4595.0 4637.6 4658.6 4788.9 5051.9 5367.2 5463.7 5538.0 3579.3 3627.4 3926.6 3928.9 3957.0 5150.9 5304.3 5400.3 5790.0 5816.4 3654.6 3872.7 3883.9 3889.9 3894.5 3767.5 3787.7 3893.4 4005.6 4019.3 3821.5 4081.2 4611.7 4624.5 4638.0 4650.5 5275.6 5542.3 5551.8 5600.0 1167.9 1354.7 1374.5 1378.6 1379.5 670.6 744.9 843.3 845.7 858.4 3865.6 3868.3 3948.4 4285.6 4304.4 3892.8 4182.7 4595.6 4604.9 4695.2 3817.0 4148.6 4275.7 4289.0 4312.4 2019.4 2392.4 2492.9 2493.9 2512.9 4200.4 4543.8 4711.1 4818.8 4856.7 4310.3 4364.5 4444.5 4484.8 4802.1 4263.4 4520.6 4692.9 4822.4 4862.3 4235.9 4328.6 4381.3 4637.8 4653.1 4539.6 4637.8 4668.6 4682.3 4691.6 3875.8 4099.7 4525.3 4899.6 5045.0 3621.6 3871.8 3946.9 3964.0 3976.9 5050.3 5693.3 5736.3 5771.5 5782.1 4733.5 5101.7 5540.7 5619.4 5620.7 5561.0 5563.0 5590.5 5622.4 5627.8 5112.2 5238.0 5448.1 5449.3 5476.6 4887.5 5351.0 5367.4 5590.0 5595.2 5459.3 5495.3 5511.5 5513.3 5544.3 06:17:02 INFO - PROCESS | 1941 | 06:17:02 INFO - PROCESS | 1941 | [#5] yui.html Cycles:180 Average:24787.04 Median:24661.94 stddev:38397.18 (155.7%) stddev-sans-first:38462.13 06:17:02 INFO - PROCESS | 1941 | Values: 652.5 748.6 790.7 861.7 866.1 15222.2 16495.5 16518.8 16544.5 16545.9 13263.3 14807.9 15012.7 15031.7 15032.5 668.8 699.6 885.5 905.0 907.5 714.3 898.9 901.1 906.3 906.6 944.4 958.1 958.9 959.6 961.0 738.8 954.8 955.5 957.2 958.5 553.0 554.5 554.7 561.4 692.5 15389.7 15581.8 15600.7 15638.8 15661.0 12102.2 14356.8 14724.6 14731.3 14765.8 12344.5 13261.6 13536.7 13782.9 13818.7 4530.4 4653.9 4659.9 4788.0 4841.6 125368.4 125634.0 125837.0 126083.4 126180.5 11344.6 11430.0 11568.5 11924.6 12194.5 23263.7 23816.5 24621.8 24638.8 24685.1 7501.6 8437.3 8468.7 8482.8 8592.7 4567.6 4595.5 4640.5 4646.1 4724.4 3648.7 3772.2 3776.5 4035.9 4201.6 740.9 755.4 764.3 766.0 767.9 568.8 606.6 665.5 671.7 704.1 7822.8 10378.8 10394.5 10411.4 10582.5 576.7 600.5 610.7 647.8 666.1 4660.7 4776.3 4783.9 4787.7 4793.3 92509.0 94557.0 117182.0 152862.0 153915.0 115218.0 120471.0 131004.0 135281.0 140679.0 95843.0 101734.0 102174.0 102974.0 103183.0 9052.0 9086.2 9099.4 9139.5 9307.8 52632.9 55607.9 62576.0 63183.4 64171.7 92816.0 95519.0 98026.0 99167.0 101621.5 13105.9 13324.7 13649.0 14187.4 14303.3 15086.4 15189.0 15378.5 15542.8 15573.5 14222.9 14554.3 14612.8 14666.7 14923.3 14323.0 14362.5 14582.4 14714.6 14784.3 13642.0 14190.5 14257.1 14282.9 14307.6 10547.0 10724.9 10978.2 11751.2 12146.6 11682.2 11744.6 11842.0 11857.7 12022.5 06:17:02 INFO - PROCESS | 1941 | -------- Summary: end -------- 06:17:02 INFO - PROCESS | 1941 | 06:17:02 INFO - TEST-INFO | 1941: exit 0 06:17:02 INFO - TEST-OK | dromaeo_css | took 1116507ms 06:17:02 INFO - TEST-START | kraken 06:17:02 INFO - operating with platform_type : mac_ 06:17:02 INFO - Initialising browser for kraken test... 06:17:02 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/tmpe8KNAx/profile http://localhost:52312/getInfo.html) 06:17:05 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 06:17:05 INFO - PROCESS | 1944 | colorDepth:24 06:17:05 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 06:17:05 INFO - PROCESS | 1944 | __metrics 06:17:05 INFO - PROCESS | 1944 | 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" 06:17:05 INFO - PROCESS | 1944 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:17:05 INFO - PROCESS | 1944 | ************************* 06:17:05 INFO - PROCESS | 1944 | A coding exception was thrown in a Promise resolution callback. 06:17:05 INFO - PROCESS | 1944 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 06:17:05 INFO - PROCESS | 1944 | 06:17:05 ERROR - PROCESS | 1944 | Full message: TypeError: win is null 06:17:05 INFO - PROCESS | 1944 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:858:17 06:17:05 INFO - PROCESS | 1944 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 06:17:05 INFO - PROCESS | 1944 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 06:17:05 INFO - PROCESS | 1944 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 06:17:05 INFO - PROCESS | 1944 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 06:17:05 INFO - PROCESS | 1944 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 06:17:05 INFO - PROCESS | 1944 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 06:17:05 INFO - PROCESS | 1944 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 06:17:05 INFO - PROCESS | 1944 | 06:17:05 INFO - PROCESS | 1944 | ************************* 06:17:06 INFO - TEST-INFO | 1944: exit 0 06:17:06 INFO - Browser initialized. 06:17:06 INFO - Running cycle 1/1 for kraken test... 06:17:06 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/tmpe8KNAx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:17:18 INFO - PROCESS | 1945 | RSS: Main: 266383360 06:17:18 INFO - PROCESS | 1945 | 06:17:20 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52312/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52312/tests/kraken/driver.html?testName=audio-beat-detection) 06:17:21 INFO - PROCESS | 1945 | RSS: Main: 314470400 06:17:21 INFO - PROCESS | 1945 | 06:17:24 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52312/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52312/tests/kraken/driver.html?testName=audio-dft) 06:17:24 INFO - PROCESS | 1945 | RSS: Main: 323555328 06:17:24 INFO - PROCESS | 1945 | 06:17:28 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52312/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52312/tests/kraken/driver.html?testName=audio-fft) 06:17:28 INFO - PROCESS | 1945 | RSS: Main: 409120768 06:17:28 INFO - PROCESS | 1945 | 06:17:31 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52312/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52312/tests/kraken/driver.html?testName=audio-oscillator) 06:17:31 INFO - PROCESS | 1945 | RSS: Main: 309174272 06:17:31 INFO - PROCESS | 1945 | 06:17:33 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52312/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52312/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:17:34 INFO - PROCESS | 1945 | RSS: Main: 312811520 06:17:34 INFO - PROCESS | 1945 | 06:17:37 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52312/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52312/tests/kraken/driver.html?testName=imaging-darkroom) 06:17:37 INFO - PROCESS | 1945 | RSS: Main: 348975104 06:17:37 INFO - PROCESS | 1945 | 06:17:40 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52312/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52312/tests/kraken/driver.html?testName=imaging-desaturate) 06:17:41 INFO - PROCESS | 1945 | RSS: Main: 350695424 06:17:41 INFO - PROCESS | 1945 | 06:17:44 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52312/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52312/tests/kraken/driver.html?testName=json-parse-financial) 06:17:44 INFO - PROCESS | 1945 | RSS: Main: 351096832 06:17:44 INFO - PROCESS | 1945 | 06:17:46 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52312/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52312/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:17:46 INFO - PROCESS | 1945 | RSS: Main: 330674176 06:17:46 INFO - PROCESS | 1945 | 06:17:48 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52312/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52312/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:17:49 INFO - PROCESS | 1945 | RSS: Main: 337170432 06:17:49 INFO - PROCESS | 1945 | 06:17:51 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52312/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52312/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:17:52 INFO - PROCESS | 1945 | RSS: Main: 339042304 06:17:52 INFO - PROCESS | 1945 | 06:17:55 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52312/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52312/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:17:55 INFO - PROCESS | 1945 | RSS: Main: 344731648 06:17:55 INFO - PROCESS | 1945 | 06:17:58 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52312/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52312/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:17:59 INFO - PROCESS | 1945 | RSS: Main: 346361856 06:17:59 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52312/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52312/tests/kraken/driver.html?testName=ai-astar) 06:18:01 INFO - PROCESS | 1945 | RSS: Main: 389672960 06:18:01 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | __start_tp_report 06:18:01 INFO - PROCESS | 1945 | _x_x_mozilla_page_load 06:18:01 INFO - PROCESS | 1945 | _x_x_mozilla_page_load_details 06:18:01 INFO - PROCESS | 1945 | |i|pagename|runs| 06:18:01 INFO - PROCESS | 1945 | |0;ai-astar;101;112;112;113;112;112;112;112;113;115 06:18:01 INFO - PROCESS | 1945 | |1;audio-beat-detection;143;108;106;161;107;106;128;106;141;107 06:18:01 INFO - PROCESS | 1945 | |2;audio-dft;185;188;189;181;184;184;181;181;185;181 06:18:01 INFO - PROCESS | 1945 | |3;audio-fft;77;74;60;132;80;78;53;53;99;80 06:18:01 INFO - PROCESS | 1945 | |4;audio-oscillator;85;88;92;72;71;68;98;70;68;100 06:18:01 INFO - PROCESS | 1945 | |5;imaging-gaussian-blur;87;84;88;86;87;84;89;85;87;84 06:18:01 INFO - PROCESS | 1945 | |6;imaging-darkroom;94;93;95;94;94;96;93;94;93;93 06:18:01 INFO - PROCESS | 1945 | |7;imaging-desaturate;86;84;83;83;84;83;85;84;86;83 06:18:01 INFO - PROCESS | 1945 | |8;json-parse-financial;54;53;53;55;55;54;54;54;54;54 06:18:01 INFO - PROCESS | 1945 | |9;json-stringify-tinderbox;64;73;75;76;74;70;70;72;72;71 06:18:01 INFO - PROCESS | 1945 | |10;stanford-crypto-aes;83;85;82;83;85;84;79;85;87;83 06:18:01 INFO - PROCESS | 1945 | |11;stanford-crypto-ccm;139;138;156;145;147;135;133;130;223;139 06:18:01 INFO - PROCESS | 1945 | |12;stanford-crypto-pbkdf2;169;166;165;164;165;166;164;182;161;162 06:18:01 INFO - PROCESS | 1945 | |13;stanford-crypto-sha256-iterative;67;66;67;67;68;67;65;67;66;66 06:18:01 INFO - PROCESS | 1945 | __end_tp_report 06:18:01 INFO - PROCESS | 1945 | __start_cc_report 06:18:01 INFO - PROCESS | 1945 | _x_x_mozilla_cycle_collect,2136 06:18:01 INFO - PROCESS | 1945 | __end_cc_report 06:18:01 INFO - PROCESS | 1945 | __startTimestamp1467638281379__endTimestamp 06:18:01 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | ------- Summary: start ------- 06:18:01 INFO - PROCESS | 1945 | Number of tests: 14 06:18:01 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:3.78 (3.4%) stddev-sans-first:1.01 06:18:01 INFO - PROCESS | 1945 | Values: 101.0 112.0 112.0 113.0 112.0 112.0 112.0 112.0 113.0 115.0 06:18:01 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:118.00 stddev:20.46 (17.3%) stddev-sans-first:20.14 06:18:01 INFO - PROCESS | 1945 | Values: 143.0 108.0 106.0 161.0 107.0 106.0 128.0 106.0 141.0 107.0 06:18:01 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.50 stddev:2.96 (1.6%) stddev-sans-first:3.11 06:18:01 INFO - PROCESS | 1945 | Values: 185.0 188.0 189.0 181.0 184.0 184.0 181.0 181.0 185.0 181.0 06:18:01 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | [#3] audio-fft Cycles:10 Average:78.60 Median:77.50 stddev:23.46 (30.3%) stddev-sans-first:24.87 06:18:01 INFO - PROCESS | 1945 | Values: 77.0 74.0 60.0 132.0 80.0 78.0 53.0 53.0 99.0 80.0 06:18:01 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | [#4] audio-oscillator Cycles:10 Average:81.20 Median:78.50 stddev:12.80 (16.3%) stddev-sans-first:13.51 06:18:01 INFO - PROCESS | 1945 | Values: 85.0 88.0 92.0 72.0 71.0 68.0 98.0 70.0 68.0 100.0 06:18:01 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.00 stddev:1.79 (2.1%) stddev-sans-first:1.87 06:18:01 INFO - PROCESS | 1945 | Values: 87.0 84.0 88.0 86.0 87.0 84.0 89.0 85.0 87.0 84.0 06:18:01 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:0.99 (1.1%) stddev-sans-first:1.05 06:18:01 INFO - PROCESS | 1945 | Values: 94.0 93.0 95.0 94.0 94.0 96.0 93.0 94.0 93.0 93.0 06:18:01 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.00 stddev:1.20 (1.4%) stddev-sans-first:1.05 06:18:01 INFO - PROCESS | 1945 | Values: 86.0 84.0 83.0 83.0 84.0 83.0 85.0 84.0 86.0 83.0 06:18:01 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:0.67 (1.2%) stddev-sans-first:0.71 06:18:01 INFO - PROCESS | 1945 | Values: 54.0 53.0 53.0 55.0 55.0 54.0 54.0 54.0 54.0 54.0 06:18:01 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:72.50 stddev:3.37 (4.6%) stddev-sans-first:2.13 06:18:01 INFO - PROCESS | 1945 | Values: 64.0 73.0 75.0 76.0 74.0 70.0 70.0 72.0 72.0 71.0 06:18:01 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.50 stddev:2.17 (2.6%) stddev-sans-first:2.29 06:18:01 INFO - PROCESS | 1945 | Values: 83.0 85.0 82.0 83.0 85.0 84.0 79.0 85.0 87.0 83.0 06:18:01 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | [#11] stanford-crypto-ccm Cycles:10 Average:148.50 Median:142.00 stddev:27.24 (19.2%) stddev-sans-first:28.67 06:18:01 INFO - PROCESS | 1945 | Values: 139.0 138.0 156.0 145.0 147.0 135.0 133.0 130.0 223.0 139.0 06:18:01 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.40 Median:165.50 stddev:5.91 (3.6%) stddev-sans-first:6.19 06:18:01 INFO - PROCESS | 1945 | Values: 169.0 166.0 165.0 164.0 165.0 166.0 164.0 182.0 161.0 162.0 06:18:01 INFO - PROCESS | 1945 | 06:18:01 INFO - PROCESS | 1945 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:0.84 (1.3%) stddev-sans-first:0.88 06:18:01 INFO - PROCESS | 1945 | Values: 67.0 66.0 67.0 67.0 68.0 67.0 65.0 67.0 66.0 66.0 06:18:01 INFO - PROCESS | 1945 | -------- Summary: end -------- 06:18:01 INFO - PROCESS | 1945 | 06:18:02 INFO - TEST-INFO | 1945: exit 0 06:18:02 INFO - TEST-OK | kraken | took 59321ms 06:18:02 INFO - SUITE-END | took 1175s 06:18:02 INFO - Completed test suite (00:19:36) 06:18:02 INFO - javascript benchmark 06:18:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3298.7361011119115, 3303.719183869371, 3330.5337252784607, 3342.7994320340767, 3368.0, 5649.6327738696955, 5713.685747283897, 5719.084946408576, 5787.971060144699, 5807.245058142441, 5700.087985922253, 5731.856703582413, 5819.563532735041, 5827.562932780037, 5843.912341314884, 5117.641765076446, 5140.7172605506685, 5185.870353241162, 5186.636935414523, 5327.866803329928, 5296.967091417175, 5316.0962636351915, 5319.973400132994, 5340.813071542497, 5367.597430192732, 4375.671824613167, 5138.486151384869, 5181.844544663666, 5188.014277287303, 5250.212468129773, 5149.3305870237, 5156.303898973628, 5185.248139019859, 5263.789448422059, 5270.7628156733035, 5160.045391602565, 5171.534561889448, 5188.6367954243215, 5217.921731174036, 5285.392179899322, 5673.120666296731, 5815.73829177688, 5862.824115276549, 5880.8235752927485, 5887.676177810219, 6180.47465965389, 6209.192804935375, 6255.155554000197, 6260.436560709558, 6330.87338253237, 6274.184356033732, 6305.117283580303, 6311.6213027218055, 6311.684415779193, 6337.841553961188, 5733.512651424592, 5907.5864689471755, 5944.227250457413, 5958.970205148947, 5959.433853783884, 6207.534434917399, 6245.812625621239, 6254.74981000764, 6285.245770507477, 6336.334684858116, 6260.530460215502, 6261.467775239064, 6265.749370025239, 6273.843153921189, 6279.0895320178315, 6169.907451388232, 6270.184875966186, 6313.715882785285, 6315.0, 6342.5243106767175, 6162.568620196633, 6242.063690446469, 6245.094461303176, 6248.125262463259, 6352.174217351715, 6313.147725057151, 6314.336994615591, 6328.11406403104, 6337.17616709834, 6343.0, 4907.484714105039, 4966.404031516241, 5018.197088465828, 5041.445441001487, 5098.566621837185, 3872.4578559001197, 4451.910961780811, 4457.487388950248, 4483.0, 4563.817447302071, 5865.853353666193, 5872.148538461899, 5886.440788125121, 5898.292204935436, 5961.6124951878855, 6168.0, 6205.193324867739, 6241.78153764616, 6316.9368306317265, 6320.810375688737, 5832.8541786454825, 5906.704664766745, 5917.171595976567, 5918.674472903946, 5964.224650795369, 5507.311586051744, 5515.951969125853, 5525.861853453695, 5533.391326954032, 5533.723313834292, 4799.448063472675, 6208.068789681584, 6227.84430389253, 6251.374862513712, 6351.237851457855, 6257.56197066201, 6275.309715931153, 6285.937140628535, 6294.307626161027, 6302.936970630235, 6340.017297318923, 6342.365763423621, 6346.555741098079, 6351.269603995414, 6355.586886852339, 6126.540509461897, 6146.385361463817, 6182.103594978793, 6202.131701561875, 6241.188645476241, 6264.467520260829, 6305.495560355253, 6333.1766870306565, 6369.649669268235, 6377.585456945284, 6132.601380910227, 6170.043643235305, 6184.0, 6185.288691800501, 6188.164597779242, 5056.0, 6015.368386319374, 6104.6713747925405, 6123.862238009652, 6220.098085777628, 6056.071783054791, 6064.302605200459, 6076.574639775174, 6094.1855883981, 6136.539759517965, 5835.358110607915, 5999.760009599567, 6214.130021797043, 6214.720337584729, 6227.221597300338, 5911.527077833849, 5999.130126131599, 6027.547933905063, 6032.819015429544, 6112.572119951738], "value": 5738.098298900507, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.6346095237144, 490.7570752477523, 525.4587774592171, 535.5367607019926, 553.2862607236665, 30910.69089309106, 35464.64535354645, 44007.339889901676, 44126.77936610317, 46674.066518669606, 45799.313010304875, 46331.30503042457, 46350.304745428846, 46378.53621463785, 46524.302135467995, 8913.0, 9833.360831545962, 9999.950000250024, 10006.749831254205, 10021.348612340174, 8868.955655221715, 12088.21426607272, 12281.447334869908, 12302.876971230262, 12546.372681365941, 12285.877141228608, 12357.629271121881, 12368.567100151497, 12395.194312369713, 12435.751284974294, 8926.86609700855, 12139.57511487098, 12224.877751222462, 12290.754184916297, 12546.1844980076, 11854.229475084177, 12201.816972745415, 12266.32535210563, 12270.631881043582, 12279.877201227962, 37520.0, 43518.78240608808, 43523.78238108821, 43578.564214357764, 43747.34378984318, 3603.081214290339, 3728.2170744143764, 3755.286495565834, 3861.6331448512346, 4003.259397011562, 33259.0, 33261.833690831634, 33595.31237501019, 33919.6608033919, 34450.483242751376, 3243.659415761358, 3280.3603297357026, 3290.50642403639, 3300.405926933151, 3453.6546345365514, 371248.0, 371638.0, 521481.0, 525140.3742981261, 525307.0, 34594.48108278378, 65571.0, 66034.0, 66133.37066405306, 66277.0, 34689.65310346915, 36651.4502282466, 36676.26647467036, 36676.899693009254, 36786.63213367885, 14639.92680036593, 14886.478973235884, 14906.254687265593, 14913.92543037278, 14920.880933929782, 10685.732856678489, 11343.886561134448, 11634.76730465386, 12484.375781210903, 12508.999280057573, 7933.603319833986, 7939.880901786478, 7988.320992715568, 7993.120756716757, 8044.793281007778, 3267.313864088568, 3282.9343413131605, 3285.589301337333, 3288.4245257079915, 3303.0, 951.70497145885, 962.6534447598859, 969.3699095587924, 980.4950450517989, 1087.8259478483405, 28773.712262877103, 30337.089887303417, 32010.839945800122, 32100.0, 32672.0, 1090.9781804364027, 1115.676453828397, 1165.5687395663658, 1167.6030149749126, 1178.1458442629025, 16722.331106755835, 16735.91632041832, 16771.161441927856, 17026.978381297162, 17038.57791225414, 661021.0, 687743.0, 689210.0, 692281.5385923139, 694058.0, 54119.72940135353, 64130.67934660297, 64975.70048599002, 65044.02433963495, 65110.67444662746, 1272.7263638317806, 1285.9164154330126, 1396.6368744126396, 1415.9646008849654, 1422.7439060969125, 32172.517412239304, 33621.0, 34002.0, 34238.65761342454, 34373.48439773455, 56436.71781641065, 57274.0, 57540.136897945726, 57540.42459575351, 57572.0, 616961.0, 629401.0, 630214.0, 631241.0, 632879.0, 37896.43155352727, 38830.805845970586, 39038.219235615696, 39944.00139996524, 43271.350929736705, 51127.0, 51552.0, 51989.220161696845, 52255.0, 52400.47599523956, 41963.75453068366, 42697.719068428, 42795.57204427916, 42837.5716242846, 43674.56325436705, 42108.94727631834, 42450.78774606107, 42453.08961096696, 42849.57150428581, 43301.783491082344, 40136.0, 40849.59150408458, 41327.38008929929, 41575.960600985214, 41729.79135104305, 33242.16894577558, 35258.94223173309, 36544.08639784026, 37653.05867353338, 37804.43293350547, 21652.376071794362, 25878.741212587633, 26539.33651658724, 26744.866275668497, 27855.44289114247], "value": 20183.334185602635, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.2054584728049, 367.49836473214066, 375.5343374215973, 376.60456520653327, 392.239056230912, 15874.365025398985, 16258.918705406471, 19992.90003549984, 20297.695534566992, 20353.796462035374, 20527.0, 20694.68957965632, 21041.47396315089, 21076.051577679, 21613.891930540325, 5248.658837175591, 5782.653040817554, 5991.520678345743, 6043.063325184604, 6053.061775424794, 5963.910541341883, 6046.3349031606485, 6079.969600151993, 6085.0, 6087.54343424245, 5872.119182122695, 6024.7288872000645, 6025.608335458203, 6200.410960958701, 6214.161088253096, 5993.250843644544, 6171.876562468748, 6208.255009398856, 6210.223722034746, 6232.470240029603, 5392.56859451243, 5762.625429347121, 5974.731137098841, 6013.248343957005, 6023.216981792383, 13591.592252232449, 13813.309334533233, 20868.26961056356, 26733.46533069347, 26755.0, 2190.5290362571964, 2216.855904366227, 2287.2109122352763, 2289.98095847348, 2370.9407264818346, 9914.752131196705, 10581.73545661357, 14823.703525929528, 14850.034747741362, 15397.407045984188, 1804.0528722420702, 1822.9453116406528, 1843.806400327973, 1883.6421079994757, 1963.5091227193202, 117043.0, 119859.4007029968, 120047.0, 120728.0, 125530.0, 11418.657440276806, 11587.246828956091, 11641.0, 11675.532978680927, 11852.88147118535, 14312.212828294336, 14366.99431039838, 14425.567232983027, 14426.639334016523, 14440.277986100864, 8407.915920840836, 8439.57802109892, 8469.576521173916, 8490.915090849136, 8582.055973842873, 8399.118092600189, 8404.663813447516, 8414.11651776567, 8522.147785221428, 8524.36067294943, 4202.453681021509, 4275.717284814543, 4556.681032327705, 4565.56627120423, 4571.954280457219, 1876.240122750261, 1918.1847714721187, 1925.768907731053, 1926.8554858385678, 1950.3661310073999, 728.7902082542953, 861.8922634670666, 876.8246350729879, 909.7407238936871, 1067.8184708599556, 20403.795962040487, 20782.58434831295, 21667.0, 21679.891600541898, 21831.781682183293, 946.2666433514082, 948.307735353196, 980.7548112971757, 999.1756800639502, 1035.7151783259512, 11108.500117494732, 11420.200585958903, 11449.255798373079, 11454.911783380565, 11469.369184694922, 589245.0, 593593.0, 608884.0, 610119.0, 610348.0, 81593.18406815856, 123744.14383784251, 135813.6418635801, 136220.0, 139470.60529394576, 1000.284796370584, 1001.1840350114634, 1032.4631191780231, 1064.861567996171, 1071.23406764164, 17800.643987119925, 18985.43043708691, 19272.0, 19311.613767724284, 19339.323123690603, 54693.453065468835, 58158.418415816996, 58708.706456467444, 58724.706376467846, 59119.70440147944, 80383.79424308523, 85372.0, 86635.0, 86805.13194868223, 86905.69641455257, 7233.63831808418, 7462.776116716507, 7573.697052117854, 7774.067111946602, 8339.20777526134, 12318.75362492763, 12324.383780811102, 12411.0, 13134.40895159701, 14071.788923166365, 9886.307958443127, 11883.940580297043, 12025.45885435175, 12113.091518136, 12210.75578488443, 10791.298565593212, 11641.708957276136, 11841.23032002917, 11872.52509899629, 12171.33057681836, 11907.880921190677, 12083.456244469196, 12175.512979480722, 12208.32854193028, 12266.815997759863, 7538.6607602659105, 7668.731594394169, 7733.149353570991, 7790.14308426084, 7792.688292468238, 11431.714207144887, 11858.407079646156, 11948.880511194777, 12129.45417456199, 12174.391280435766], "value": 9714.268226984543, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.1931079973613, 1523.2688309611385, 1757.8505827004703, 1791.0686443049622, 1963.146031476307, 8257.298129658979, 8943.0, 9132.771680707985, 9141.862872056925, 9178.632854685813, 7976.401769867254, 8188.976377952756, 8736.51949142797, 8957.059508751585, 8981.68564100255, 2375.1568193291437, 2417.383567190364, 2420.9757902420924, 2421.9152329668464, 2423.1761201191593, 2361.268006917861, 2368.7749663782, 2404.1825779235055, 2406.927792166229, 2407.16952651335, 2109.24067335759, 2187.84685072045, 2341.262502311775, 2417.540667273221, 2418.6130219164847, 2405.194259922928, 2420.5884999550117, 2422.527607116613, 2430.5260474207535, 2431.9513609727883, 2383.2611890314056, 2418.286605451396, 2421.8425802322795, 2425.7331693513697, 2427.5994460914103, 9070.954645226797, 9144.725658230263, 9149.22231610314, 9162.67141264513, 9186.356955013152, 5349.545288650469, 5514.862128446781, 5535.142052981794, 5538.418466061046, 5549.417311182308, 3539.132912436444, 3611.223586928823, 3732.328180927432, 3736.906577335561, 3739.813009349549, 1064.2124827627576, 1074.5862842805577, 1076.8923107689247, 1086.6739978006567, 1095.901368876797, 12211.389430528527, 12258.75482490354, 12273.93863030688, 12291.262524248485, 12342.382880855921, 4659.879754711598, 4696.365990591226, 4873.610111191097, 4883.975580122076, 4893.853184404474, 10194.286399951932, 10196.235282353704, 10198.33710808795, 10210.234232432596, 10218.540165692562, 5480.616356855062, 5494.917576236359, 5500.834974950758, 5503.394626591072, 5514.145307477347, 4986.700797952134, 5004.799808007711, 5023.34696489454, 5036.798528058836, 5057.544820966131, 3395.966040339565, 3422.469517224834, 3483.303339332144, 3488.75578709493, 3537.4163263061823, 1275.7320962597958, 1288.5683296095913, 1299.5841330774251, 1307.4704744578462, 1314.677903913528, 638.8530637953204, 801.7514570483169, 823.1603764160523, 884.6284560484612, 997.1275134257525, 5862.70686465675, 6068.2111325528285, 6113.32753397126, 6149.169862068564, 6234.688265586702, 958.3866325551653, 960.6109525642127, 973.6397532912725, 1019.2457581389718, 1023.918086553074, 3977.801109944491, 4124.443200167999, 4138.151678905817, 4184.393262976912, 4185.204811085884, 10689.946550267354, 10963.890361096286, 11056.834147487794, 11074.39090850011, 11093.722656933642, 9934.950325248328, 10400.531976061095, 10851.67444976652, 11051.115910727123, 11052.060574851066, 7575.204603516661, 7773.689052437952, 7776.416768742367, 7853.568053757098, 8263.967004124605, 5226.529612334908, 6034.336223015377, 6248.593841400295, 6274.121622972696, 6285.402886725754, 9768.902310976799, 10020.248481364073, 10332.276740628084, 10346.22403319769, 10351.63769268072, 10334.948325258325, 10441.008104230086, 10478.580856765644, 10491.580336786443, 10493.2130090242, 7201.4238860889955, 8248.54632995191, 8255.133211012997, 8276.834463310579, 8489.40574159803, 8332.625031873702, 8364.958175209085, 8443.577821108796, 8443.577821108796, 8457.450265732708, 6338.746450141943, 7552.735654252075, 7693.538387696756, 7761.990941177611, 7852.293293603603, 6473.126127972663, 7678.5392876427595, 7710.4217183710325, 7747.690092396241, 7759.92240077592, 7255.165655949633, 7268.055152830098, 7352.264773522605, 7353.485256032026, 7360.631968401665, 4993.600511958962, 5167.741612919414, 5187.2478490619405, 5244.4493328201515, 5272.841814745564, 4669.836555720533, 5194.740262986911, 5283.973580132229, 5301.310829592128, 5338.386085600206], "value": 4677.93781811914, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.4333386555964, 298.29453342844175, 306.04208826373457, 309.23155957445755, 313.59702781925233, 4080.8777586163783, 4146.9792651036705, 4799.640026997971, 5046.520580544847, 5279.683219006853, 4291.828326866922, 5022.723750193738, 5233.424323324441, 5233.618703489912, 5256.947430525694, 3112.8443577821076, 3434.6908778209954, 3612.530371051773, 3646.6171052039554, 4082.8775136745944, 3834.5015148030716, 3876.108495046141, 4053.952753859297, 4098.938515922264, 4110.1163249901265, 2694.528457519936, 3770.8491660333552, 3865.8067096645195, 3919.196564704229, 4030.979845100785, 3901.60983901609, 3944.0, 4066.79666016698, 4069.4302797608357, 4089.85685501009, 2687.274435902315, 2712.51174788538, 3456.3927773585406, 3522.947155792665, 3552.875649352286, 4663.802577680081, 4905.3623029006, 5072.492750724935, 5125.564327032207, 5159.7678104485385, 3778.5465744110606, 3831.961680383188, 4594.977025114887, 4637.582617564435, 4658.604018658418, 4788.880277993043, 5051.87370315745, 5367.194920761878, 5463.699496527689, 5538.0031594313, 3579.3020361029608, 3627.3833448313585, 3926.607339266079, 3928.8624898128714, 3956.9819392109944, 5150.944056468415, 5304.329136322085, 5400.270963419927, 5790.0, 5816.447437493389, 3654.6162652921594, 3872.6708229800784, 3883.902902427462, 3889.922201555953, 3894.4811523505855, 3767.547894252707, 3787.7348585598743, 3893.4159876018825, 4005.5593884673244, 4019.3167161582014, 3821.5032045833864, 4081.1837632473625, 4611.677182597186, 4624.537546245416, 4637.976810115928, 4650.51169627191, 5275.63070585063, 5542.334919809648, 5551.833444996657, 5599.972000140055, 1167.9416029198505, 1354.7290541891464, 1374.4639590559689, 1378.600205940266, 1379.4895888521312, 670.577536152221, 744.8547533230968, 843.3379796859435, 845.6659619450282, 858.4420126917554, 3865.594112618191, 3868.2843673920415, 3948.4077388391397, 4285.5928686774705, 4304.440422745023, 3892.7858967757047, 4182.728122672017, 4595.632349412039, 4604.907901842012, 4695.2252878275385, 3816.961830381716, 4148.585141485888, 4275.679324050647, 4288.957110428918, 4312.439382880269, 2019.424464027745, 2392.413858604653, 2492.9127480538455, 2493.887775050127, 2512.8869200886006, 4200.413450111345, 4543.795529201194, 4711.104890070876, 4818.807442613603, 4856.73287969158, 4310.349135281779, 4364.476262848479, 4444.4888837784065, 4484.798184081724, 4802.079168332695, 4263.4031235627035, 4520.570545798144, 4692.906141877212, 4822.42130944289, 4862.319275301461, 4235.915281694287, 4328.636479508945, 4381.342798580238, 4637.791299391468, 4653.115488227273, 4539.568740969603, 4637.791299391603, 4668.649851261237, 4682.274247491644, 4691.648126390466, 3875.825587848497, 4099.713020088565, 4525.275955847049, 4899.559039686445, 5044.974775126101, 3621.60162382143, 3871.806409679561, 3946.881593552198, 3964.0089977505622, 3976.8608098716404, 5050.292958985671, 5693.3168019837885, 5736.282964629421, 5771.538276937751, 5782.132680098019, 4733.5266473352385, 5101.7194054327365, 5540.667559946416, 5619.438056194348, 5620.690862002629, 5560.972195138998, 5562.972185139049, 5590.496855282881, 5622.357491978763, 5627.774889004394, 5112.236278483917, 5237.973810130925, 5448.101063324507, 5449.3188351456065, 5476.58925580591, 4887.535684110004, 5351.0, 5367.44344140195, 5590.0, 5595.216669666332, 5459.262999495176, 5495.2856128703015, 5511.511891789275, 5513.338399392099, 5544.3346798384455], "value": 3785.506164695104, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.5008368598023, 748.5695724958149, 790.735103740247, 861.7414775567328, 866.0516734176077, 15222.162781047038, 16495.505134845946, 16518.83481165188, 16544.503664890057, 16545.91727041366, 13263.336833158352, 14807.85192148081, 15012.699746005073, 15031.699366012674, 15032.473863414783, 668.7926742709764, 699.6361891816252, 885.5483703311326, 905.0180554098811, 907.4600612635504, 714.2607401339615, 898.874157617934, 901.0538934119181, 906.3338446242007, 906.5920335848862, 944.4333399960003, 958.089814676057, 958.9424634521916, 959.5777857742606, 960.9903900961006, 738.7931379213775, 954.8185844689522, 955.4553904274567, 957.1816097236866, 958.53990084759, 552.9944700552983, 554.4732504121101, 554.7365001624237, 561.3712641841123, 692.4737199728193, 15389.692206155925, 15581.844181558152, 15600.687986240326, 15638.843611563967, 15661.0, 12102.213356131911, 14356.784648230285, 14724.55826325212, 14731.263436828223, 14765.7785133222, 12344.5062197512, 13261.602151935458, 13536.661583460394, 13782.93108534461, 13818.65453363664, 4530.365748795171, 4653.906921861578, 4659.906801863977, 4788.0, 4841.612670986347, 125368.37315813453, 125634.0, 125837.0, 126083.36958315241, 126180.47639047168, 11344.602938897098, 11430.0, 11568.537258509565, 11924.593604081605, 12194.451249693786, 23263.651045234335, 23816.523669526858, 24621.753782462303, 24638.753612463646, 24685.136020239566, 7501.5874126923545, 8437.325013998865, 8468.66125354979, 8482.787930301669, 8592.699255526026, 4567.566081222279, 4595.540445955445, 4640.535946405333, 4646.070785842845, 4724.385829842098, 3648.6533779290926, 3772.188979369394, 3776.546814382292, 4035.8991025224605, 4201.579842015774, 740.870347689163, 755.3730403764862, 764.2548515197637, 766.0118447203157, 767.9232076792388, 568.795233715862, 606.6269244414741, 665.5241502325861, 671.70445004198, 704.1409480433863, 7822.843543129219, 10378.792424151627, 10394.532246048946, 10411.427371494641, 10582.523786429476, 576.6828244465527, 600.4845494136372, 610.703808652803, 647.8218489915216, 666.1240468783534, 4660.650451216105, 4776.259679749644, 4783.856484305476, 4787.736674482937, 4793.257045158006, 92509.0, 94557.0, 117182.0, 152862.0, 153915.0, 115218.0, 120471.0, 131004.0, 135281.0, 140679.0, 95843.0, 101734.0, 102173.95652086768, 102974.0, 103183.0, 9052.0, 9086.182243598108, 9099.36304458708, 9139.543022848698, 9307.813843723223, 52632.94734105373, 55607.88784224374, 62576.0, 63183.36816631959, 64171.67914160586, 92816.0, 95519.0, 98026.0, 99167.0, 101621.49189254007, 13105.934470327587, 13324.666883327995, 13649.0, 14187.432502699776, 14303.284835757962, 15086.396544138111, 15189.0, 15378.461753838563, 15542.766858497358, 15573.532794016197, 14222.857771422154, 14554.345054472362, 14612.78080828767, 14666.706665866835, 14923.253837307873, 14323.0, 14362.497312594009, 14582.416703331748, 14714.55856324312, 14784.334704938088, 13642.0, 14190.50333238373, 14257.073290236101, 14282.928585357007, 14307.64230894236, 10546.997408514633, 10724.927507249213, 10978.176636752116, 11751.236169648946, 12146.574869879512, 11682.182247242952, 11744.58893938708, 11842.0, 11857.703557411134, 12022.519099235933], "value": 8097.75091447491, "unit": "score"}], "name": "dromaeo_css", "value": 7378.373264468392, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 112.0, 113.0, 112.0, 112.0, 112.0, 112.0, 113.0, 115.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 108.0, 106.0, 161.0, 107.0, 106.0, 128.0, 106.0, 141.0, 107.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 188.0, 189.0, 181.0, 184.0, 184.0, 181.0, 181.0, 185.0, 181.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 60.0, 132.0, 80.0, 78.0, 53.0, 53.0, 99.0, 80.0], "value": 78.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 92.0, 72.0, 71.0, 68.0, 98.0, 70.0, 68.0, 100.0], "value": 81.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 86.0, 87.0, 84.0, 89.0, 85.0, 87.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 95.0, 94.0, 94.0, 96.0, 93.0, 94.0, 93.0, 93.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 83.0, 83.0, 84.0, 83.0, 85.0, 84.0, 86.0, 83.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 55.0, 55.0, 54.0, 54.0, 54.0, 54.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 73.0, 75.0, 76.0, 74.0, 70.0, 70.0, 72.0, 72.0, 71.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 82.0, 83.0, 85.0, 84.0, 79.0, 85.0, 87.0, 83.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 156.0, 145.0, 147.0, 135.0, 133.0, 130.0, 223.0, 139.0], "value": 148.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 166.0, 165.0, 164.0, 165.0, 166.0, 164.0, 182.0, 161.0, 162.0], "value": 166.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 67.0, 68.0, 67.0, 65.0, 67.0, 66.0, 66.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1431.3000000000002, "alertThreshold": 2.0}]} 06:18:02 INFO - Return code: 0 06:18:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:18:02 ERROR - # TBPL SUCCESS # 06:18:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:18:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:18:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:18:02 INFO - 2016-07-04 06:18:02.670 screenresolution[1949:22338] starting screenresolution argv=screenresolution get 06:18:02 INFO - 2016-07-04 06:18:02.673 screenresolution[1949:22338] Display 0: 1600x1200x32@60 06:18:02 INFO - 2016-07-04 06:18:02.680 screenresolution[1950:22341] starting screenresolution argv=screenresolution list 06:18:02 INFO - Available Modes on Display 0 06:18:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:18:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:18:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:18:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:18:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:18:02 INFO - Intel Iris: 06:18:02 INFO - Chipset Model: Intel Iris 06:18:02 INFO - Type: GPU 06:18:02 INFO - Bus: Built-In 06:18:02 INFO - VRAM (Dynamic, Max): 1536 MB 06:18:02 INFO - Vendor: Intel (0x8086) 06:18:02 INFO - Device ID: 0x0a2e 06:18:02 INFO - Revision ID: 0x0009 06:18:02 INFO - Displays: 06:18:02 INFO - Display: 06:18:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:18:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:18:02 INFO - Display Serial Number: 1600x1200 60 06:18:02 INFO - Main Display: Yes 06:18:02 INFO - Mirror: Off 06:18:02 INFO - Online: Yes 06:18:02 INFO - Rotation: Supported 06:18:02 INFO - Return code: 0 06:18:02 INFO - Running post-action listener: _resource_record_post_action 06:18:02 INFO - ##### 06:18:02 INFO - ##### Finished run-tests step (success) 06:18:02 INFO - ##### 06:18:02 INFO - Running post-run listener: _resource_record_post_run 06:18:02 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 946176; Write bytes: 705144320; Read time: 6; Write time: 1991 06:18:02 INFO - TinderboxPrint: CPU usage
53.5% 06:18:02 INFO - TinderboxPrint: I/O read bytes / time
946,176 / 6 06:18:02 INFO - TinderboxPrint: I/O write bytes / time
705,144,320 / 1,991 06:18:02 INFO - TinderboxPrint: CPU idle
2,256.2 (46.5%) 06:18:02 INFO - TinderboxPrint: CPU system
965.7 (19.9%) 06:18:02 INFO - TinderboxPrint: CPU user
1,627.7 (33.6%) 06:18:02 INFO - TinderboxPrint: Swap in / out
407,556,096 / 0 06:18:02 INFO - install - Wall time: 36s; CPU: 55.0%; Read bytes: 394113536; Write bytes: 387835392; Read time: 29765; Write time: 855 06:18:02 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 946176; Write bytes: 294631936; Read time: 6; Write time: 1074 06:18:03 INFO - Running post-run listener: _upload_blobber_files 06:18:03 INFO - Blob upload gear active. 06:18:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:18:03 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 06:18:03 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'] 06:18:03 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 06:18:03 INFO - (blobuploader) - INFO - Open directory for files ... 06:18:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:18:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:18:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:04 INFO - (blobuploader) - INFO - Done attempting. 06:18:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:18:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:18:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:05 INFO - (blobuploader) - INFO - Done attempting. 06:18:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:18:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:18:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:07 INFO - (blobuploader) - INFO - Done attempting. 06:18:07 INFO - (blobuploader) - INFO - Iteration through files over. 06:18:07 INFO - Return code: 0 06:18:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:18:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:18:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a3a6eb1ceb0768f833a516a79a84e73866107d83de17b9c2ec6c2d06f1e9ce30ba7c7f77a35149ffab0a35666c062937fa32712879613c849bc7cf1bf821ef17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5082d671ca4bc0885eac3232c5e55b4031ce62ad88a5bf1cb69ffa4d955109181bb98be4e64cc2eddd5171bc40e318b90a817d20dde64ed09fd54f1d3b5f677f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5aaf7d12648c98f33699daf773be4ce53187eb662ca3f985bf7eab04515be924b16cb614907ec92f3a67e81c9a319cd01109634b04af8d27f3a5033f2537ba1b"} 06:18:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:18:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:18:07 INFO - Contents: 06:18:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a3a6eb1ceb0768f833a516a79a84e73866107d83de17b9c2ec6c2d06f1e9ce30ba7c7f77a35149ffab0a35666c062937fa32712879613c849bc7cf1bf821ef17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5082d671ca4bc0885eac3232c5e55b4031ce62ad88a5bf1cb69ffa4d955109181bb98be4e64cc2eddd5171bc40e318b90a817d20dde64ed09fd54f1d3b5f677f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5aaf7d12648c98f33699daf773be4ce53187eb662ca3f985bf7eab04515be924b16cb614907ec92f3a67e81c9a319cd01109634b04af8d27f3a5033f2537ba1b"} 06:18:07 INFO - Running post-run listener: copy_logs_to_upload_dir 06:18:07 INFO - Copying logs to upload dir... 06:18:07 INFO - mkdir: /builds/slave/test/build/upload/logs 06:18:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.124006 ========= master_lag: 0.14 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-04 06:18:07.903864) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 8 secs) (at 2016-07-04 06:18:07.907760) ========= 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.yyuxdDYowj/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.EjDYaiewuf/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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/a3a6eb1ceb0768f833a516a79a84e73866107d83de17b9c2ec6c2d06f1e9ce30ba7c7f77a35149ffab0a35666c062937fa32712879613c849bc7cf1bf821ef17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5082d671ca4bc0885eac3232c5e55b4031ce62ad88a5bf1cb69ffa4d955109181bb98be4e64cc2eddd5171bc40e318b90a817d20dde64ed09fd54f1d3b5f677f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5aaf7d12648c98f33699daf773be4ce53187eb662ca3f985bf7eab04515be924b16cb614907ec92f3a67e81c9a319cd01109634b04af8d27f3a5033f2537ba1b"} build_url:https://queue.taskcluster.net/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011217 build_url: 'https://queue.taskcluster.net/v1/task/dS9ciUapSQmAuy1BIc4ZXA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a3a6eb1ceb0768f833a516a79a84e73866107d83de17b9c2ec6c2d06f1e9ce30ba7c7f77a35149ffab0a35666c062937fa32712879613c849bc7cf1bf821ef17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5082d671ca4bc0885eac3232c5e55b4031ce62ad88a5bf1cb69ffa4d955109181bb98be4e64cc2eddd5171bc40e318b90a817d20dde64ed09fd54f1d3b5f677f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5aaf7d12648c98f33699daf773be4ce53187eb662ca3f985bf7eab04515be924b16cb614907ec92f3a67e81c9a319cd01109634b04af8d27f3a5033f2537ba1b"}' ========= master_lag: 8.67 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 8 secs) (at 2016-07-04 06:18:16.588534) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 06:18:16.588937) ========= 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.yyuxdDYowj/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.EjDYaiewuf/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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.005153 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 06:18:16.625258) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 06:18:16.625770) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 06:18:16.626110) ========= ========= Total master_lag: 8.92 =========