builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0027 starttime: 1460781160.0 results: success (0) buildid: 20160415194322 builduid: 18656c48ba05448299ddadb961c91d29 revision: 42a0a7f409d1b9a40770e36a66af6ccf67539cc4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 21:32:40.001420) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 21:32:40.001854) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 21:32:40.002124) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 21:32:40.018992) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 21:32:40.019266) ========= 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.Qq2mlRuqhJ/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.buMC1hIPsr/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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-04-15 21:32:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.14M=0.001s 2016-04-15 21:32:40 (8.14 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119566 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 21:32:40.155081) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 21:32:40.155391) ========= 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.Qq2mlRuqhJ/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.buMC1hIPsr/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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.020055 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 21:32:40.203056) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-04-15 21:32:40.203368) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 42a0a7f409d1b9a40770e36a66af6ccf67539cc4 --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 42a0a7f409d1b9a40770e36a66af6ccf67539cc4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Qq2mlRuqhJ/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.buMC1hIPsr/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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-04-15 21:32:40,280 truncating revision to first 12 chars 2016-04-15 21:32:40,280 Setting DEBUG logging. 2016-04-15 21:32:40,280 attempt 1/10 2016-04-15 21:32:40,280 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/42a0a7f409d1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-15 21:32:41,412 unpacking tar archive at: fx-team-42a0a7f409d1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.382106 ========= master_lag: 2.56 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-04-15 21:32:44.144489) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 21:32:44.144957) ========= script_repo_revision: 42a0a7f409d1b9a40770e36a66af6ccf67539cc4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 21:32:44.145350) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-15 21:32:44.145617) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-15 21:32:44.208125) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-15 21:32:44.208404) ========= /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.Qq2mlRuqhJ/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.buMC1hIPsr/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 21:32:44 INFO - MultiFileLogger online at 20160415 21:32:44 in /builds/slave/test 21:32:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 21:32:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:32:44 INFO - {'append_to_log': False, 21:32:44 INFO - 'base_work_dir': '/builds/slave/test', 21:32:44 INFO - 'blob_upload_branch': 'Fx-Team', 21:32:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:32:44 INFO - 'branch': 'Fx-Team', 21:32:44 INFO - 'buildbot_json_path': 'buildprops.json', 21:32:44 INFO - 'config_files': ('talos/mac_config.py',), 21:32:44 INFO - 'default_actions': ('clobber', 21:32:44 INFO - 'read-buildbot-config', 21:32:44 INFO - 'download-and-extract', 21:32:44 INFO - 'populate-webroot', 21:32:44 INFO - 'create-virtualenv', 21:32:44 INFO - 'install', 21:32:44 INFO - 'run-tests'), 21:32:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:32:44 INFO - 'download_minidump_stackwalk': True, 21:32:44 INFO - 'download_symbols': 'ondemand', 21:32:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:32:44 INFO - 'tooltool.py': '/tools/tooltool.py', 21:32:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:32:44 INFO - '/tools/misc-python/virtualenv.py')}, 21:32:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:32:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:32:44 INFO - 'installer_path': 'installer.exe', 21:32:44 INFO - 'log_level': 'info', 21:32:44 INFO - 'log_name': 'talos', 21:32:44 INFO - 'log_to_console': True, 21:32:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:32:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:32:44 INFO - 'opt_config_files': (), 21:32:44 INFO - 'pip_index': False, 21:32:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:32:44 INFO - 'cmd': ('bash', 21:32:44 INFO - '-c', 21:32:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:32:44 INFO - 'enabled': True, 21:32:44 INFO - 'halt_on_failure': False, 21:32:44 INFO - 'name': 'check_screen_resolution'},), 21:32:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:32:44 INFO - 'cmd': ('bash', 21:32:44 INFO - '-c', 21:32:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:32:44 INFO - 'enabled': True, 21:32:44 INFO - 'halt_on_failure': False, 21:32:44 INFO - 'name': 'check_screen_resolution'},), 21:32:44 INFO - 'run_cmd_checks_enabled': True, 21:32:44 INFO - 'sps_profile': False, 21:32:44 INFO - 'sps_profile_interval': 0, 21:32:44 INFO - 'suite': 'dromaeojs', 21:32:44 INFO - 'system_bits': '32', 21:32:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:32:44 INFO - 'title': 't-yosemite-r7-0027', 21:32:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:32:44 INFO - 'use_talos_json': True, 21:32:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:32:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:32:44 INFO - 'work_dir': 'build'} 21:32:44 INFO - ##### 21:32:44 INFO - ##### Running clobber step. 21:32:44 INFO - ##### 21:32:44 INFO - Running pre-action listener: _resource_record_pre_action 21:32:44 INFO - Running main action method: clobber 21:32:44 INFO - rmtree: /builds/slave/test/build 21:32:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:32:45 INFO - Running post-action listener: _resource_record_post_action 21:32:45 INFO - ##### 21:32:45 INFO - ##### Running read-buildbot-config step. 21:32:45 INFO - ##### 21:32:45 INFO - Running pre-action listener: _resource_record_pre_action 21:32:45 INFO - Running main action method: read_buildbot_config 21:32:45 INFO - Using buildbot properties: 21:32:45 INFO - { 21:32:45 INFO - "project": "", 21:32:45 INFO - "product": "firefox", 21:32:45 INFO - "script_repo_revision": "production", 21:32:45 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 21:32:45 INFO - "repository": "", 21:32:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 21:32:45 INFO - "buildid": "20160415194322", 21:32:45 INFO - "pgo_build": "False", 21:32:45 INFO - "basedir": "/builds/slave/test", 21:32:45 INFO - "buildnumber": 668, 21:32:45 INFO - "slavename": "t-yosemite-r7-0027", 21:32:45 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 21:32:45 INFO - "platform": "yosemite_r7", 21:32:45 INFO - "scheduler": "tests-fx-team-macosx64-talos", 21:32:45 INFO - "branch": "fx-team", 21:32:45 INFO - "repo_path": "integration/fx-team", 21:32:45 INFO - "revision": "42a0a7f409d1b9a40770e36a66af6ccf67539cc4", 21:32:45 INFO - "stage_platform": "macosx64", 21:32:45 INFO - "builduid": "18656c48ba05448299ddadb961c91d29", 21:32:45 INFO - "slavebuilddir": "test" 21:32:45 INFO - } 21:32:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 21:32:45 INFO - Running post-action listener: _resource_record_post_action 21:32:45 INFO - ##### 21:32:45 INFO - ##### Running download-and-extract step. 21:32:45 INFO - ##### 21:32:45 INFO - Running pre-action listener: _resource_record_pre_action 21:32:45 INFO - Running main action method: download_and_extract 21:32:45 INFO - mkdir: /builds/slave/test/build/tests 21:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:32:45 INFO - https://queue.taskcluster.net/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 21:32:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 21:32:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 21:32:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 21:32:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 21:32:46 INFO - Downloaded 1199 bytes. 21:32:46 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 21:32:46 INFO - Using the following test package requirements: 21:32:46 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 21:32:46 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 21:32:46 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 21:32:46 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 21:32:46 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 21:32:46 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 21:32:46 INFO - u'jsshell-mac.zip'], 21:32:46 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 21:32:46 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 21:32:46 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 21:32:46 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 21:32:46 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 21:32:46 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 21:32:46 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 21:32:46 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 21:32:46 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 21:32:46 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 21:32:46 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 21:32:46 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:32:46 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')]} 21:32:46 INFO - https://queue.taskcluster.net/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:32:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 21:32:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 21:32:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 21:32:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:32:47 INFO - Downloaded 18032080 bytes. 21:32:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:32:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 21:32:50 INFO - Return code: 0 21:32:50 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:32:50 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')]} 21:32:50 INFO - https://queue.taskcluster.net/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:32:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 21:32:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 21:32:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 21:32:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:32:52 INFO - Downloaded 18032080 bytes. 21:32:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:32:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 21:32:56 INFO - Return code: 0 21:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:32:56 INFO - https://queue.taskcluster.net/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 21:32:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 21:32:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 21:32:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 21:32:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:32:57 INFO - Downloaded 10841076 bytes. 21:32:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:32:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 21:32:57 INFO - Return code: 0 21:32:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:32:57 INFO - https://queue.taskcluster.net/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 21:32:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 21:32:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 21:32:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 21:32:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 21:33:00 INFO - Downloaded 109849373 bytes. 21:33:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 21:33:00 INFO - mkdir: /builds/slave/test/properties 21:33:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:33:00 INFO - Writing to file /builds/slave/test/properties/build_url 21:33:00 INFO - Contents: 21:33:00 INFO - build_url:https://queue.taskcluster.net/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 21:33:01 INFO - Running post-action listener: _resource_record_post_action 21:33:01 INFO - Running post-action listener: set_extra_try_arguments 21:33:01 INFO - ##### 21:33:01 INFO - ##### Running populate-webroot step. 21:33:01 INFO - ##### 21:33:01 INFO - Running pre-action listener: _resource_record_pre_action 21:33:01 INFO - Running main action method: populate_webroot 21:33:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:33:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:33:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:33:01 INFO - '--tppagecycles', 21:33:01 INFO - '7'], 21:33:01 INFO - 'tests': ['tsvgm']}}, 21:33:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:33:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:33:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:33:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:33:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:33:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:33:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:33:01 INFO - 'talos_options': ['--disable-e10s'], 21:33:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:33:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:33:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:33:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:33:01 INFO - 'talos_options': ['--disable-e10s'], 21:33:01 INFO - 'tests': ['damp', 'tps']}, 21:33:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:33:01 INFO - 'tests': ['damp', 'tps']}, 21:33:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:33:01 INFO - 'tests': ['dromaeo_dom']}, 21:33:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:33:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:33:01 INFO - 'tests': ['a11yr', 21:33:01 INFO - 'ts_paint', 21:33:01 INFO - 'tpaint', 21:33:01 INFO - 'sessionrestore', 21:33:01 INFO - 'sessionrestore_no_auto_restore', 21:33:01 INFO - 'tabpaint']}, 21:33:01 INFO - 'other-e10s': {'tests': ['a11yr', 21:33:01 INFO - 'ts_paint', 21:33:01 INFO - 'tpaint', 21:33:01 INFO - 'sessionrestore', 21:33:01 INFO - 'sessionrestore_no_auto_restore', 21:33:01 INFO - 'tabpaint']}, 21:33:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:33:01 INFO - 'ts_paint', 21:33:01 INFO - 'tpaint', 21:33:01 INFO - 'sessionrestore', 21:33:01 INFO - 'sessionrestore_no_auto_restore', 21:33:01 INFO - 'tabpaint']}, 21:33:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:33:01 INFO - 'ts_paint', 21:33:01 INFO - 'tpaint', 21:33:01 INFO - 'sessionrestore', 21:33:01 INFO - 'sessionrestore_no_auto_restore', 21:33:01 INFO - 'tabpaint']}, 21:33:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:33:01 INFO - 'tests': ['a11yr', 21:33:01 INFO - 'ts_paint', 21:33:01 INFO - 'tpaint', 21:33:01 INFO - 'sessionrestore', 21:33:01 INFO - 'sessionrestore_no_auto_restore', 21:33:01 INFO - 'tabpaint']}, 21:33:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:33:01 INFO - 'tests': ['a11yr', 21:33:01 INFO - 'ts_paint', 21:33:01 INFO - 'tpaint', 21:33:01 INFO - 'sessionrestore', 21:33:01 INFO - 'sessionrestore_no_auto_restore', 21:33:01 INFO - 'tabpaint']}, 21:33:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:33:01 INFO - 'tests': ['tsvgx', 21:33:01 INFO - 'tsvgr_opacity', 21:33:01 INFO - 'tart', 21:33:01 INFO - 'tscrollx', 21:33:01 INFO - 'cart']}, 21:33:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:33:01 INFO - 'tsvgr_opacity', 21:33:01 INFO - 'tart', 21:33:01 INFO - 'tscrollx', 21:33:01 INFO - 'cart']}, 21:33:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:33:01 INFO - 'talos_options': ['--disable-e10s'], 21:33:01 INFO - 'tests': ['tp5o']}, 21:33:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:33:01 INFO - 'tests': ['tp5o']}, 21:33:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:33:01 INFO - 'talos_options': ['--disable-e10s', 21:33:01 INFO - '--xperf_path', 21:33:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:33:01 INFO - 'tests': ['tp5n']}, 21:33:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:33:01 INFO - 'talos_options': ['--xperf_path', 21:33:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:33:01 INFO - 'tests': ['tp5n']}}, 21:33:01 INFO - 'talos.zip': {'path': '', 21:33:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:33:01 INFO - Running post-action listener: _resource_record_post_action 21:33:01 INFO - ##### 21:33:01 INFO - ##### Running create-virtualenv step. 21:33:01 INFO - ##### 21:33:01 INFO - Running pre-action listener: _resource_record_pre_action 21:33:01 INFO - Running main action method: create_virtualenv 21:33:01 INFO - Creating virtualenv /builds/slave/test/build/venv 21:33:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:33:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:33:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:33:01 INFO - Using real prefix '/tools/python27' 21:33:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:33:02 INFO - Installing distribute.............................................................................................................................................................................................done. 21:33:05 INFO - Installing pip.................done. 21:33:05 INFO - Return code: 0 21:33:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1014aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f585a1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10134ae00>, '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 0x101173db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101345b20>, '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.Qq2mlRuqhJ/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.buMC1hIPsr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:33:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qq2mlRuqhJ/Render', 21:33:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:33:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:33:05 INFO - 'HOME': '/Users/cltbld', 21:33:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:33:05 INFO - 'LOGNAME': 'cltbld', 21:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:05 INFO - 'MOZ_NO_REMOTE': '1', 21:33:05 INFO - 'NO_EM_RESTART': '1', 21:33:05 INFO - 'PAGER': '/bin/cat', 21:33:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:05 INFO - 'PWD': '/builds/slave/test', 21:33:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:33:05 INFO - 'SHELL': '/bin/bash', 21:33:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buMC1hIPsr/Listeners', 21:33:05 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 21:33:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:33:05 INFO - 'USER': 'cltbld', 21:33:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:33:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:05 INFO - 'XPC_FLAGS': '0x0', 21:33:05 INFO - 'XPC_SERVICE_NAME': '0', 21:33:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 21:33:05 INFO - Cleaning up... 21:33:05 INFO - Return code: 0 21:33:05 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 21:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1014aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f585a1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10134ae00>, '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 0x101173db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101345b20>, '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.Qq2mlRuqhJ/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.buMC1hIPsr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 21:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 21:33:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qq2mlRuqhJ/Render', 21:33:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:33:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:33:05 INFO - 'HOME': '/Users/cltbld', 21:33:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:33:05 INFO - 'LOGNAME': 'cltbld', 21:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:05 INFO - 'MOZ_NO_REMOTE': '1', 21:33:05 INFO - 'NO_EM_RESTART': '1', 21:33:05 INFO - 'PAGER': '/bin/cat', 21:33:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:05 INFO - 'PWD': '/builds/slave/test', 21:33:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:33:05 INFO - 'SHELL': '/bin/bash', 21:33:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buMC1hIPsr/Listeners', 21:33:05 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 21:33:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:33:05 INFO - 'USER': 'cltbld', 21:33:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:33:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:05 INFO - 'XPC_FLAGS': '0x0', 21:33:05 INFO - 'XPC_SERVICE_NAME': '0', 21:33:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:05 INFO - Downloading/unpacking psutil>=0.7.1 21:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:33:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:33:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:33:08 INFO - Installing collected packages: psutil 21:33:08 INFO - Running setup.py install for psutil 21:33:08 INFO - building 'psutil._psutil_osx' extension 21:33:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 21:33:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 21:33:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 21:33:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 21:33:09 INFO - building 'psutil._psutil_posix' extension 21:33:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 21:33:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:33:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:33:09 INFO - ^ 21:33:09 INFO - 1 warning generated. 21:33:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 21:33:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:33:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:33:09 INFO - Successfully installed psutil 21:33:09 INFO - Cleaning up... 21:33:09 INFO - Return code: 0 21:33:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 21:33:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:33:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:09 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1014aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f585a1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10134ae00>, '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 0x101173db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101345b20>, '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.Qq2mlRuqhJ/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.buMC1hIPsr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:33:09 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.0.0'] in /builds/slave/test/build 21:33:09 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.0.0 21:33:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qq2mlRuqhJ/Render', 21:33:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:33:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:33:09 INFO - 'HOME': '/Users/cltbld', 21:33:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:33:09 INFO - 'LOGNAME': 'cltbld', 21:33:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:09 INFO - 'MOZ_NO_REMOTE': '1', 21:33:09 INFO - 'NO_EM_RESTART': '1', 21:33:09 INFO - 'PAGER': '/bin/cat', 21:33:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:33:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:09 INFO - 'PWD': '/builds/slave/test', 21:33:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:33:09 INFO - 'SHELL': '/bin/bash', 21:33:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buMC1hIPsr/Listeners', 21:33:09 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 21:33:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:33:09 INFO - 'USER': 'cltbld', 21:33:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:33:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:33:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:09 INFO - 'XPC_FLAGS': '0x0', 21:33:09 INFO - 'XPC_SERVICE_NAME': '0', 21:33:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:33:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:09 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:33:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:33:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:33:12 INFO - Installing collected packages: mozsystemmonitor 21:33:12 INFO - Running setup.py install for mozsystemmonitor 21:33:12 INFO - Successfully installed mozsystemmonitor 21:33:12 INFO - Cleaning up... 21:33:12 INFO - Return code: 0 21:33:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:33:12 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')]} 21:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:12 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')]} 21:33:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:12 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 0x1014aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f585a1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10134ae00>, '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 0x101173db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101345b20>, '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.Qq2mlRuqhJ/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.buMC1hIPsr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:33:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:33:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:33:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qq2mlRuqhJ/Render', 21:33:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:33:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:33:12 INFO - 'HOME': '/Users/cltbld', 21:33:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:33:12 INFO - 'LOGNAME': 'cltbld', 21:33:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:12 INFO - 'MOZ_NO_REMOTE': '1', 21:33:12 INFO - 'NO_EM_RESTART': '1', 21:33:12 INFO - 'PAGER': '/bin/cat', 21:33:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:33:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:12 INFO - 'PWD': '/builds/slave/test', 21:33:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:33:12 INFO - 'SHELL': '/bin/bash', 21:33:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buMC1hIPsr/Listeners', 21:33:12 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 21:33:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:33:12 INFO - 'USER': 'cltbld', 21:33:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:33:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:33:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:12 INFO - 'XPC_FLAGS': '0x0', 21:33:12 INFO - 'XPC_SERVICE_NAME': '0', 21:33:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:33:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:12 INFO - Downloading/unpacking blobuploader==1.2.4 21:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:15 INFO - Downloading blobuploader-1.2.4.tar.gz 21:33:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:33:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:33:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:33:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:33:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:16 INFO - Downloading docopt-0.6.1.tar.gz 21:33:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:33:16 INFO - Installing collected packages: blobuploader, requests, docopt 21:33:16 INFO - Running setup.py install for blobuploader 21:33:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:33:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:33:16 INFO - Running setup.py install for requests 21:33:17 INFO - Running setup.py install for docopt 21:33:17 INFO - Successfully installed blobuploader requests docopt 21:33:17 INFO - Cleaning up... 21:33:17 INFO - Return code: 0 21:33:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:33:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:33:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:33:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:17 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 0x1014aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f585a1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10134ae00>, '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 0x101173db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101345b20>, '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.Qq2mlRuqhJ/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.buMC1hIPsr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:33:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:33:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qq2mlRuqhJ/Render', 21:33:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:33:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:33:17 INFO - 'HOME': '/Users/cltbld', 21:33:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:33:17 INFO - 'LOGNAME': 'cltbld', 21:33:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:17 INFO - 'MOZ_NO_REMOTE': '1', 21:33:17 INFO - 'NO_EM_RESTART': '1', 21:33:17 INFO - 'PAGER': '/bin/cat', 21:33:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:33:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:17 INFO - 'PWD': '/builds/slave/test', 21:33:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:33:17 INFO - 'SHELL': '/bin/bash', 21:33:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buMC1hIPsr/Listeners', 21:33:17 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 21:33:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:33:17 INFO - 'USER': 'cltbld', 21:33:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:33:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:33:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:17 INFO - 'XPC_FLAGS': '0x0', 21:33:17 INFO - 'XPC_SERVICE_NAME': '0', 21:33:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:33:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:33:17 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-dCII4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:33:17 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ew1kqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:33:17 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Qyr3W2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:33:18 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-_HeSQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:33:18 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-brVXkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:33:18 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-gYXSS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:33:18 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-7J_Z27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:33:18 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-vppmC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:33:18 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-smg6pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:33:18 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-2M28kd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:33:18 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-qNQA49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:33:18 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-5HEf1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:33:18 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-eq1hTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:33:19 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-s9442S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:33:19 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-MfP9Qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:33:19 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-EAuI_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:33:19 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ZS_2ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:33:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:33:19 INFO - Running setup.py install for manifestparser 21:33:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:33:19 INFO - Running setup.py install for mozcrash 21:33:19 INFO - Running setup.py install for mozdebug 21:33:19 INFO - Running setup.py install for mozdevice 21:33:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:33:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:33:20 INFO - Running setup.py install for mozfile 21:33:20 INFO - Running setup.py install for mozhttpd 21:33:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:33:20 INFO - Running setup.py install for mozinfo 21:33:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:33:20 INFO - Running setup.py install for mozInstall 21:33:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:33:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:33:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:33:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:33:20 INFO - Running setup.py install for mozleak 21:33:20 INFO - Running setup.py install for mozlog 21:33:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:33:20 INFO - Running setup.py install for moznetwork 21:33:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:33:21 INFO - Running setup.py install for mozprocess 21:33:21 INFO - Running setup.py install for mozprofile 21:33:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:33:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:33:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:33:21 INFO - Running setup.py install for mozrunner 21:33:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:33:21 INFO - Running setup.py install for mozscreenshot 21:33:21 INFO - Running setup.py install for moztest 21:33:21 INFO - Running setup.py install for mozversion 21:33:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:33:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:33:22 INFO - Cleaning up... 21:33:22 INFO - Return code: 0 21:33:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:33:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:33:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:33:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:22 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 0x1014aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f585a1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10134ae00>, '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 0x101173db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101345b20>, '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.Qq2mlRuqhJ/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.buMC1hIPsr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:33:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:33:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:33:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qq2mlRuqhJ/Render', 21:33:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:33:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:33:22 INFO - 'HOME': '/Users/cltbld', 21:33:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:33:22 INFO - 'LOGNAME': 'cltbld', 21:33:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:22 INFO - 'MOZ_NO_REMOTE': '1', 21:33:22 INFO - 'NO_EM_RESTART': '1', 21:33:22 INFO - 'PAGER': '/bin/cat', 21:33:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:33:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:22 INFO - 'PWD': '/builds/slave/test', 21:33:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:33:22 INFO - 'SHELL': '/bin/bash', 21:33:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buMC1hIPsr/Listeners', 21:33:22 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 21:33:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:33:22 INFO - 'USER': 'cltbld', 21:33:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:33:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:33:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:22 INFO - 'XPC_FLAGS': '0x0', 21:33:22 INFO - 'XPC_SERVICE_NAME': '0', 21:33:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:33:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:33:22 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-3HK0lc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 21:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:33:22 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-lFNvUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:33:22 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Mwe6p3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:33:22 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-cEZJXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:33:22 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-9cSvgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:33:22 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-e6acZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:33:22 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-tjtObr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:33:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-mPhskh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:33:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-YL_M_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:33:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-V2YzIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:33:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-RXCSfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:33:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-EGPhzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:33:23 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)) 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:33:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-WqdNnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:33:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-H67c6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:33:23 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)) 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:33:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-MlCBNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:33:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-qMqUrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:33:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:33:24 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ahWWpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:33:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:33:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:33:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:33:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:33:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:33:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:33:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:33: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 21:33: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 21:33: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 21:33: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 21:33: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 21:33: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 21:33:26 INFO - Downloading blessings-1.6.tar.gz 21:33:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:33:26 INFO - Installing collected packages: blessings 21:33:26 INFO - Running setup.py install for blessings 21:33:26 INFO - Successfully installed blessings 21:33:26 INFO - Cleaning up... 21:33:26 INFO - Return code: 0 21:33:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:33:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:33:27 INFO - Reading from file tmpfile_stdout 21:33:27 INFO - Current package versions: 21:33:27 INFO - blessings == 1.6 21:33:27 INFO - blobuploader == 1.2.4 21:33:27 INFO - docopt == 0.6.1 21:33:27 INFO - manifestparser == 1.1 21:33:27 INFO - mozInstall == 1.12 21:33:27 INFO - mozcrash == 0.17 21:33:27 INFO - mozdebug == 0.1 21:33:27 INFO - mozdevice == 0.48 21:33:27 INFO - mozfile == 1.2 21:33:27 INFO - mozhttpd == 0.7 21:33:27 INFO - mozinfo == 0.9 21:33:27 INFO - mozleak == 0.1 21:33:27 INFO - mozlog == 3.1 21:33:27 INFO - moznetwork == 0.27 21:33:27 INFO - mozprocess == 0.22 21:33:27 INFO - mozprofile == 0.28 21:33:27 INFO - mozrunner == 6.11 21:33:27 INFO - mozscreenshot == 0.1 21:33:27 INFO - mozsystemmonitor == 0.0 21:33:27 INFO - moztest == 0.7 21:33:27 INFO - mozversion == 1.4 21:33:27 INFO - psutil == 3.1.1 21:33:27 INFO - requests == 1.2.3 21:33:27 INFO - wsgiref == 0.1.2 21:33:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:33:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:33:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:27 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 0x1014aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f585a1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10134ae00>, '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 0x101173db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101345b20>, '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.Qq2mlRuqhJ/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.buMC1hIPsr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:33:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:33:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:33:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qq2mlRuqhJ/Render', 21:33:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:33:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:33:27 INFO - 'HOME': '/Users/cltbld', 21:33:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:33:27 INFO - 'LOGNAME': 'cltbld', 21:33:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:27 INFO - 'MOZ_NO_REMOTE': '1', 21:33:27 INFO - 'NO_EM_RESTART': '1', 21:33:27 INFO - 'PAGER': '/bin/cat', 21:33:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:33:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:27 INFO - 'PWD': '/builds/slave/test', 21:33:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:33:27 INFO - 'SHELL': '/bin/bash', 21:33:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buMC1hIPsr/Listeners', 21:33:27 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 21:33:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:33:27 INFO - 'USER': 'cltbld', 21:33:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:33:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:33:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:27 INFO - 'XPC_FLAGS': '0x0', 21:33:27 INFO - 'XPC_SERVICE_NAME': '0', 21:33:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:33:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:33:27 INFO - Cleaning up... 21:33:27 INFO - Return code: 0 21:33:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:33:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:33:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:27 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 0x1014aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f585a1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10134ae00>, '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 0x101173db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101345b20>, '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.Qq2mlRuqhJ/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.buMC1hIPsr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:33:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:33:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:33:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qq2mlRuqhJ/Render', 21:33:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:33:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:33:27 INFO - 'HOME': '/Users/cltbld', 21:33:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:33:27 INFO - 'LOGNAME': 'cltbld', 21:33:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:27 INFO - 'MOZ_NO_REMOTE': '1', 21:33:27 INFO - 'NO_EM_RESTART': '1', 21:33:27 INFO - 'PAGER': '/bin/cat', 21:33:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:33:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:27 INFO - 'PWD': '/builds/slave/test', 21:33:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:33:27 INFO - 'SHELL': '/bin/bash', 21:33:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buMC1hIPsr/Listeners', 21:33:27 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 21:33:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:33:27 INFO - 'USER': 'cltbld', 21:33:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:33:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:33:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:27 INFO - 'XPC_FLAGS': '0x0', 21:33:27 INFO - 'XPC_SERVICE_NAME': '0', 21:33:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:33:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:27 INFO - Downloading/unpacking jsonschema 21:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:33:30 INFO - Downloading/unpacking functools32 (from jsonschema) 21:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:30 INFO - Downloading functools32-3.2.3-2.tar.gz 21:33:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:33:30 INFO - warning: no files found matching '*.txt' 21:33:30 INFO - no previously-included directories found matching 'build' 21:33:30 INFO - no previously-included directories found matching 'dist' 21:33:30 INFO - no previously-included directories found matching '.git*' 21:33:30 INFO - Installing collected packages: jsonschema, functools32 21:33:30 INFO - Running setup.py install for functools32 21:33:30 INFO - warning: no files found matching '*.txt' 21:33:30 INFO - no previously-included directories found matching 'build' 21:33:30 INFO - no previously-included directories found matching 'dist' 21:33:30 INFO - no previously-included directories found matching '.git*' 21:33:30 INFO - Successfully installed jsonschema functools32 21:33:30 INFO - Cleaning up... 21:33:30 INFO - Return code: 0 21:33:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:33:30 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')]} 21:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:30 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')]} 21:33:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:30 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1014aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f585a1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10134ae00>, '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 0x101173db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101345b20>, '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.Qq2mlRuqhJ/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.buMC1hIPsr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:33:30 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', 'flake8'] in /builds/slave/test/build 21:33:30 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 flake8 21:33:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qq2mlRuqhJ/Render', 21:33:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:33:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:33:30 INFO - 'HOME': '/Users/cltbld', 21:33:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:33:30 INFO - 'LOGNAME': 'cltbld', 21:33:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:30 INFO - 'MOZ_NO_REMOTE': '1', 21:33:30 INFO - 'NO_EM_RESTART': '1', 21:33:30 INFO - 'PAGER': '/bin/cat', 21:33:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:33:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:30 INFO - 'PWD': '/builds/slave/test', 21:33:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:33:30 INFO - 'SHELL': '/bin/bash', 21:33:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buMC1hIPsr/Listeners', 21:33:30 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 21:33:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:33:30 INFO - 'USER': 'cltbld', 21:33:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:33:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:33:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:30 INFO - 'XPC_FLAGS': '0x0', 21:33:30 INFO - 'XPC_SERVICE_NAME': '0', 21:33:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:33:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:31 INFO - Downloading/unpacking flake8 21:33: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 21:33: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 21:33: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 21:33: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 21:33: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 21:33: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 21:33:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:33:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:33:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:33:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:33:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:33: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 21:33: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 21:33: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 21:33: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 21:33: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 21:33: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 21:33:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:33:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:33:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:33:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:33:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:33:35 INFO - no previously-included directories found matching 'docs/_build' 21:33:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:33:35 INFO - Running setup.py install for pep8 21:33:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:33:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:33:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:33:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:33:35 INFO - no previously-included directories found matching 'docs/_build' 21:33:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:33:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:33:35 INFO - Cleaning up... 21:33:35 INFO - Return code: 0 21:33:35 INFO - Running post-action listener: _resource_record_post_action 21:33:35 INFO - Running post-action listener: _start_resource_monitoring 21:33:35 INFO - Starting resource monitoring. 21:33:35 INFO - ##### 21:33:35 INFO - ##### Running install step. 21:33:35 INFO - ##### 21:33:35 INFO - Running pre-action listener: _resource_record_pre_action 21:33:35 INFO - Running main action method: install 21:33:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:33:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:33:35 INFO - Reading from file tmpfile_stdout 21:33:35 INFO - Detecting whether we're running mozinstall >=1.0... 21:33:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:33:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:33:35 INFO - Reading from file tmpfile_stdout 21:33:35 INFO - Output received: 21:33:35 INFO - Usage: mozinstall [options] installer 21:33:35 INFO - Options: 21:33:35 INFO - -h, --help show this help message and exit 21:33:35 INFO - -d DEST, --destination=DEST 21:33:35 INFO - Directory to install application into. [default: 21:33:35 INFO - "/builds/slave/test"] 21:33:35 INFO - --app=APP Application being installed. [default: firefox] 21:33:35 INFO - mkdir: /builds/slave/test/build/application 21:33:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:33:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:34:09 INFO - Reading from file tmpfile_stdout 21:34:09 INFO - Output received: 21:34:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:34:09 INFO - Running post-action listener: _resource_record_post_action 21:34:09 INFO - ##### 21:34:09 INFO - ##### Running run-tests step. 21:34:09 INFO - ##### 21:34:09 INFO - Running pre-action listener: _resource_record_pre_action 21:34:09 INFO - Running main action method: run_tests 21:34:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:34:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:34:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:34:09 INFO - 2016-04-15 21:34:09.629 screenresolution[1936:10694] starting screenresolution argv=screenresolution get 21:34:09 INFO - 2016-04-15 21:34:09.631 screenresolution[1936:10694] Display 0: 1600x1200x32@60 21:34:09 INFO - 2016-04-15 21:34:09.637 screenresolution[1937:10696] starting screenresolution argv=screenresolution list 21:34:09 INFO - Available Modes on Display 0 21:34:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:34:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:34:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:34:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:34:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:34:09 INFO - Intel Iris: 21:34:09 INFO - Chipset Model: Intel Iris 21:34:09 INFO - Type: GPU 21:34:09 INFO - Bus: Built-In 21:34:09 INFO - VRAM (Dynamic, Max): 1536 MB 21:34:09 INFO - Vendor: Intel (0x8086) 21:34:09 INFO - Device ID: 0x0a2e 21:34:09 INFO - Revision ID: 0x0009 21:34:09 INFO - Displays: 21:34:09 INFO - Display: 21:34:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:34:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:34:09 INFO - Display Serial Number: 1600x1200 60 21:34:09 INFO - Main Display: Yes 21:34:09 INFO - Mirror: Off 21:34:09 INFO - Online: Yes 21:34:09 INFO - Rotation: Supported 21:34:09 INFO - Return code: 0 21:34:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:34:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:34:09 INFO - Python 2.7.3 21:34:09 INFO - Return code: 0 21:34:09 INFO - grabbing minidump binary from tooltool 21:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:34:09 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10134ae00>, '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 0x101173db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101345b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:34:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:34:09 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 21:34:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:34:09 INFO - Return code: 0 21:34:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:34:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:34:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:34:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:34:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:34:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:34:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:34:11 INFO - Return code: 0 21:34:11 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/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:34:11 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/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0027 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:34:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qq2mlRuqhJ/Render', 21:34:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:11 INFO - 'HOME': '/Users/cltbld', 21:34:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:11 INFO - 'LOGNAME': 'cltbld', 21:34:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:34:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:34:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:11 INFO - 'MOZ_NO_REMOTE': '1', 21:34:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:34:11 INFO - 'NO_EM_RESTART': '1', 21:34:11 INFO - 'PAGER': '/bin/cat', 21:34:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:11 INFO - 'PWD': '/builds/slave/test', 21:34:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:34:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:11 INFO - 'SHELL': '/bin/bash', 21:34:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buMC1hIPsr/Listeners', 21:34:11 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 21:34:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:11 INFO - 'USER': 'cltbld', 21:34:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:11 INFO - 'XPC_FLAGS': '0x0', 21:34:11 INFO - 'XPC_SERVICE_NAME': '0', 21:34:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:11 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/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:34:11 INFO - mozversion application_buildid: 20160415194322 21:34:11 INFO - mozversion application_changeset: 42a0a7f409d1b9a40770e36a66af6ccf67539cc4 21:34:11 INFO - mozversion application_display_name: Nightly 21:34:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:34:11 INFO - mozversion application_name: Firefox 21:34:11 INFO - mozversion application_remotingname: firefox 21:34:11 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:34:11 INFO - mozversion application_vendor: Mozilla 21:34:11 INFO - mozversion application_version: 48.0a1 21:34:11 INFO - mozversion platform_buildid: 20160415194322 21:34:11 INFO - mozversion platform_changeset: 42a0a7f409d1b9a40770e36a66af6ccf67539cc4 21:34:11 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:34:11 INFO - mozversion platform_version: 48.0a1 21:34:11 INFO - using testdate: 1460781251 21:34:11 INFO - actual date: 1460781251 21:34:11 INFO - starting webserver on 'localhost:49669' 21:34:11 INFO - SUITE-START | Running 2 tests 21:34:11 INFO - TEST-START | dromaeo_css 21:34:11 INFO - operating with platform_type : mac_ 21:34:11 INFO - Initialising browser for dromaeo_css test... 21:34:11 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmp_k_wzl/profile http://localhost:49669/getInfo.html) 21:34:13 INFO - PROCESS | 1955 | __metrics Screen width/height:1600/1200 21:34:13 INFO - PROCESS | 1955 | colorDepth:24 21:34:13 INFO - PROCESS | 1955 | Browser inner width/height: 1024/689 21:34:13 INFO - PROCESS | 1955 | __metrics 21:34:13 INFO - PROCESS | 1955 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:34:13 ERROR - PROCESS | 1955 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:34:14 INFO - TEST-INFO | 1955: exit 0 21:34:14 INFO - Browser initialized. 21:34:14 INFO - Running cycle 1/1 for dromaeo_css test... 21:34:14 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmp_k_wzl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:34:26 INFO - PROCESS | 1957 | RSS: Main: 237314048 21:34:26 INFO - PROCESS | 1957 | 21:35:17 INFO - PROCESS | 1957 | 1460781317760 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:37:28 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49669/tests/dromaeo/cssquery-ext.html) 21:37:29 INFO - PROCESS | 1957 | RSS: Main: 372125696 21:37:29 INFO - PROCESS | 1957 | 21:40:32 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-ext.html (next: http://localhost:49669/tests/dromaeo/cssquery-jquery.html) 21:40:33 INFO - PROCESS | 1957 | RSS: Main: 377434112 21:40:33 INFO - PROCESS | 1957 | 21:43:35 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49669/tests/dromaeo/cssquery-mootools.html) 21:43:36 INFO - PROCESS | 1957 | RSS: Main: 378585088 21:43:36 INFO - PROCESS | 1957 | 21:46:38 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49669/tests/dromaeo/cssquery-prototype.html) 21:46:38 INFO - PROCESS | 1957 | RSS: Main: 374190080 21:46:38 INFO - PROCESS | 1957 | 21:49:41 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49669/tests/dromaeo/cssquery-yui.html) 21:49:42 INFO - PROCESS | 1957 | RSS: Main: 340725760 21:49:42 INFO - PROCESS | 1957 | 21:52:45 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-yui.html (next: http://localhost:49669/tests/dromaeo/cssquery-dojo.html) 21:52:45 INFO - PROCESS | 1957 | RSS: Main: 383062016 21:52:45 INFO - PROCESS | 1957 | 21:52:45 INFO - PROCESS | 1957 | __start_tp_report 21:52:45 INFO - PROCESS | 1957 | _x_x_mozilla_page_load 21:52:45 INFO - PROCESS | 1957 | _x_x_mozilla_page_load_details 21:52:45 INFO - PROCESS | 1957 | |i|pagename|runs| 21:52:45 INFO - PROCESS | 1957 | |0;dojo.html;3350.8994730158097;3367.932641347173;3400.2859399526096;3420.4527275635887;3434.9484757728637;5536.2249285100115;5587.525060369869;5596.804111856086;5612.803551875675;5624.91562626561;5625.465580769831;5663.66018038918;5732.5987180897355;5752.108423194411;5758.7408566614495;5100.183970564692;5116.283720279173;5139.331886854704;5161.329027226455;5283.894322113555;5248.107821670325;5276.287701160332;5286.31277933868;5293.68237905725;5357.4106848246665;5118.283440318359;5127.948720512822;5204.29741984831;5238.895222095556;5263.4473380295285;5186.559142472895;5197.22041693749;5201.479852014806;5203.271541984125;5233.895322093536;5130.743462826844;5156.535911767959;5158.226266060084;5166.095933211673;5198.220266959948;5442.2380866678695;5476.534494567926;5582.497575218209;5613.578981576357;5661.745221465044;6064.96967515164;6104.641056839901;6107.236595425572;6109.511239100906;6138.8772224555705;6154.261488621394;6206.503479721611;6211.192544969125;6215.502759779207;6224.408681175281;5601.991641504527;5662.37713851476;5699.544036477072;5706.914396284101;5773.278340207474;6283.717232724538;6284.717187726564;6286.497080233662;6305.180326557518;6309.242890853127;6033.909491357633;6048.092786082123;6054.878902422015;6056.273247210363;6063.514918806485;6081.695915204222;6130.049842274455;6144.064619644798;6167.876642467215;6235.376462353819;6048.667323297261;6083.756649733961;6089.817305480843;6112.786052488142;6116.694165291718;6102.913431175913;6110.867747202496;6123.1606109212;6129.938700612936;6139;4818;4826.95173048265;4838.346823178826;4846.248831431134;4914.483979182206;4213.578642135823;4299.720518166309;4367.956320436755;4369.650427965755;4423.800928958204;4893.559579637849;5575.52608028314;5578.665280083208;5594.9720251398485;5602.635828671123;6110.205673262448;6115.877682446415;6186.288576813724;6214.689265536705;6270.9372906270355;5578.24693666358;5599.972000139973;5610.13042978339;5640.153976903497;5645.040343141676;5309.495597918191;5328.946710532922;5330.840074797762;5358.705271210121;5383.0041442333295;6184.288806787097;6228.34602366745;6269.937300626935;6290;6314.336994615683;6206.10011548332;6247.31279559239;6249.906251406142;6253.1245625613365;6263.467605253604;6285.59143655661;6292.4966002720585;6300.306966233802;6319.557630965788;6327.683615819283;4966.6771659842;6031.306399764083;6078.7264573093435;6108.372425856702;6125.815438131279;6181.35095814933;6223.533235007302;6237.002079667232;6248.718807653758;6251.937480625135;5650.971745141413;5653.434656534479;5999.040153575407;6016.127661489147;6042.456178943916;5821.476067153976;5906.616069955439;5974.074018527135;6024.518038556817;6050.969745151246;5819.76720931158;5834.329052159056;5840.094785308284;5842;5842.591018628656;5970.671613061323;5975.43233392827;5998.190244316961;6010.969945150421;6064.666443345658;5697.515711164431;5784.190213370048;5815.360310365941;5825.76696932118;5830.387809280134 21:52:45 INFO - PROCESS | 1957 | |1;ext.html;504.8233118408557;532.5712801195036;542.1650657986701;586.2613107484568;587.1486344800041;30189.698103018964;30702.38595228094;30730.846345768266;30781.92263270786;38452.80773596135;41460.79269603648;42219.577804221946;42263;42326.7883660582;42363.576364236345;9399.436033837974;9642.614295428175;9647.903520964806;9657.4205547667;9657.46883921384;8325.66697332106;8638.827223455528;8924.687635932743;9724.319297649168;11569.13231507636;11667.474963626615;11800.7639847203;11801.468933897995;11813.822792658117;11827.645170644895;11653.592124275652;11782.410879455992;11783.999999999956;11789.056875449944;11791.941040294743;8600.483970961763;8901.287896968292;8918.866217006751;11373.658790236306;11731.94134029333;39035.41446878299;39429.60570394288;39437.67591375156;39567;39633.60366396328;3248;3316.502524621302;3326.101952472819;3889.4165875118674;4095.1195492968855;28018;28402.587220697726;28610.284742881388;28611;28759.85620071907;2660.348214687395;2735.5896615507636;2742.9588556171675;2744.588311753233;2837.645294338208;441168;442404;442446;443617;444534.77732611453;63317;65134.34865651378;65174;65215.34784652188;65350.67324663441;66510.33489665139;66868;66871.33128668748;67018.66490667516;67095;16394.83605163933;16895.408660697063;16948.491545253662;16961.49115526536;16974.83025169757;11803.17377783564;13022.609321720363;13085;13106.934465327613;13129.949604031655;16239.944403613727;17988.820111798977;18333.3583324583;18354.174062167236;18642.44072677822;6173.302341856025;6725.394714475623;7059.82350441243;7174.569525828467;7338.816529586697;969.9903000970041;1002.7643503776607;1019.9541020654088;1078.5847448732138;1127.9097672186206;28070.85964570205;29767.25581860471;29893.701062989527;29980;30428.54357184644;1079.9460026998618;1129.3562669278597;1152.0495591137346;1210.6489118155812;1210.7033776724759;15281.847181528265;15466.994645348017;15501.752245924277;15851.682966340608;15907.443239486794;606017;615066;615181;615276;615998.920005406;62393.752124957246;62814;62956.37043629597;63026.73946521136;63449;1385.9514916977857;1390.5967269491734;1413.5900588829124;1491.0382803091945;1506.3974410235774;28776;29242;29650;29768;30549.54175687411;50915.74542127266;50943;50949.7452512735;51237.48762512327;51593;402853;404946;405079;405516;405567;39324.213515730095;39465.60534394619;40294.59705403026;40298.59701402948;40393.59606404016;44627.55372446234;46573.50698697544;47172;48539.27191092207;48553.02893942031;35420.645793541735;36027.63972360243;36176.27647447089;36914.26171476609;36922.892313230645;34016.82991585026;34325.141871453416;34723;35036.12409689676;35057.82471087628;34894.825525872206;35480;35853.82073089618;36138.91583252507;36996.07509812276;31450.84274578704;32773.50839737358;32800;32857.34285314328;32905;21287.787122128582;23819.761802381756;24250.832131504758;24825.62761558614;24868 21:52:45 INFO - PROCESS | 1957 | |2;jquery.html;387.9476270703455;392.80752431308633;393.2371199872248;394.3690095846646;404.21178701532;14795.408183672667;18350.632987340246;19107;20109.698354524695;20120.597588048247;16251;19287.517812054706;19751.913644749533;20398;20731.274405395816;3976.860809871655;4507.639388848901;4535.7958891849785;4569.337446070318;4571.9542804571865;4254.4043833863125;6067;6123.2039834821635;6124.938750612481;6345.270293916213;6676.666166691671;6680.3319668033055;6684.2647308795995;6696;6700.899486507707;6442.4201821835795;6551.901721474182;6648.534602577822;6649.235337936151;6659.200895892475;5519.668819870821;5782.9421705782825;5784.971075144639;5808.30300363959;5811.506021988136;25632.615510767166;25847.35381615456;26558.804853781625;26691.332716682045;26748.19755407341;2146.8497205195645;2159.6112699714045;2202.394341556069;2268.3648578398074;2273.715785526809;14763.114213147246;15060.548183554401;15121.319540620698;15716;15978.520644380687;1738.3394310162057;1768.142450911307;1780.3501721871553;1801.2704854533936;1918.3094086128983;110577.34133987916;114209.42895285554;117025;117690.41154794171;118415.63168736576;8914.55427228636;9809.558569864374;10711.892881071246;10991.560337586567;11210.887891120985;14143.929280353737;14153.858461415253;14229.217393043275;14255.144691318346;14261.572152835432;7263.963680181565;7407.555546667218;7662.390897911389;7823.843523129505;8649.091845356274;7262.491625586264;8592.011918629438;8608.354373421893;8729.388942774072;8731.388802783744;6550.3449655035065;6689.331066893272;6709.563878347892;6713.56361836479;6715.1941766988275;1837.5865430278081;1865.5522674558008;1872.4382685194387;1889.4426144287468;1899.5346140195463;955.3646824861528;955.9617615295449;982.5283863745442;1007.9848802267971;1070.2240875365299;19360.225590976203;20359.245554373432;21110.683339749918;21117.894410528155;21267.78732212689;983.1004630762915;983.3264214013424;1008.7478130467383;1083.2525557365393;1142.6457798082622;10278.743031424274;11449.942750286362;11466.656000320003;11525.827112593317;11542.365169915734;509323;514955;517173;572416;599303;116348.41825790817;137211;139304;139466;148309;1018.8267994440963;1047.3925123428394;1061.6443491430457;1064.292245656645;1068.3056013591072;18027.549311267325;18319.81680183181;18442;18546.907265463586;18729.344472943383;47374.05251895012;47964.280535791295;48246.40624298377;49070.75464622654;49124.75437622789;69873;71188;72086;72216.63891680718;72248.63875680589;6741;6991.615461149686;7544.773656790305;7997.120316765035;8514.531700756517;11707.941460292644;12687;12728.554500592434;12906.806397904227;13009.934950325187;10978.615748448767;11251.15616328795;11385.487653055798;11426;11469.827952580552;10652.09457196147;10882.238243322892;10978.835317470404;11193.66419007431;11221.158413118885;6120.418560236771;8336.749897503085;9549.949505554245;9938.403695778277;11228.775424491629;7471;7547.73582924595;7599.202083781344;7856.304179469838;7878.369730421668;11299.661010169708;11363.204575679954;11401.20191586581;11421.942890285827;11659.825102623638 21:52:45 INFO - PROCESS | 1957 | |3;mootools.html;1432.448507324681;1611.194402798601;1628.4463282483957;1904.7238150468183;2033.5831154613306;7962.044554653443;8657.61040753166;8891.244244239231;8896.332775041865;8908.242799362053;8494.320454363651;8717.607707653155;8719.5640217989;8722.127787221274;8734.432261902972;2192.7368715754133;2411.5176964607062;2476.4551798604325;2509.297396728919;2518.559252130879;2504.3864253257975;2532.3036165054573;2533.1134103063964;2533.5312967101054;2536;2443.8900249488725;2498.2005758157397;2499.0628514307136;2500.2624225853415;2504.5992641177413;2168.9783102169013;2417.4439878827793;2471.1721573272885;2493.2021753039035;2499.512595043967;2446.694163229596;2484.3043947694673;2506.1103308325646;2511.836730612504;2511.899524019037;8691.826163476759;8747.081556436546;8754;8770.473771573663;8793.20861122502;5229.111051121318;5362.570994320445;5417.485338892839;5421.2952316199035;5440.319960004999;3577.2487777566744;3582.8387722552548;3628.4375921732176;3664.76179048361;3691.1141326081815;1039.7296702857236;1053.3153450257316;1054.8997845204694;1065.477915821242;1065.7495488560182;11561.942190289079;11596.710082247926;11601.477933493014;11608.245464044894;11617.361045142514;4646.140464014168;4662.95337046632;4853.102176097434;4889.975550122226;4896.559309662076;9872.210223182128;9891.7032489024;9929.25530585209;9958.352707074162;9961.452120133463;5364.8122315718765;5379.569634429236;5389.137737961907;5409.188621706697;5413.458654134633;4404.7357158570585;4593.977030114828;4775.379200703956;4928.778204980785;4967.428745694291;4009.1580768038457;4014.8119274995197;4089.26393249218;4119.938200926988;4165.042040330741;1346.3470216944775;1364.6929440875724;1366.774482210444;1367.8700523450257;1377.2769296119618;845.966161353539;902.3007169443603;902.5397047505819;947.2895328503622;956.3598428694821;5549.878624443637;5610.298712660918;5661.264035675418;5662.971685141548;5827.562932780059;455.3238440915238;455.84501269568506;462.88427893026744;471.94808571057155;472.1030042918482;3687.8711786041245;3786.3184626767447;3818.236352729465;3852.4799152114665;3863.188730366598;10191.643292484727;10290.279680422298;10554.366737995744;10696.304740192018;10797.028267455968;10489;10641;10653.9467302663;10660.946695266473;10665.306755061052;7307.976883236352;7760.223977602194;7894.64474098667;8168.142345053802;8236.629351679188;5482.28730265079;5550.389457159628;5579.609427340048;5645.294338207724;5737.8565535861935;9591.856122158033;9725;9788.119069283799;9833.213342932406;10004.199664027006;9342.065793420603;9872.802543949225;9877.654282100091;10023.949880250551;10135.543900524346;7209.855802883808;7395.556266624019;7519.661615227437;7521.661525231487;7539.1706912238515;8233.670653173805;8235.629396677285;8325.375596830141;8334;8357.874631880648;7325.230850760593;7355.742549010759;7399.445041622008;7506.8498630026;7552.584607846621;7194.820129496805;7202.387797037101;7324.157721862054;7329.377002954809;7410.6665200065045;6632.2372927114;6664.66676666155;6690.096836926952;6705.932940670531;6713.6643167842385;4642.0483800820075;4732.432108146907;4789.712617242976;4846.612271018381;4990.15167421532;4188.91622167561;4779.139754844162;4867.95132048675;4889.511048895082;4945.18404463274 21:52:45 INFO - PROCESS | 1957 | |4;prototype.html;308.8239703369081;315.9273367125561;323.98380080995946;326.0528165678704;328.2876158735544;5845.152452894333;5889.440503152199;5923.229980102586;5942.46329038058;5946.464818166364;5290.68255904646;5450.209719590657;5558.44415558444;5763.798267060653;5858.589898707081;4425.8229670813125;4460.50934397216;4464.776761161929;4469.4636643602735;4517.977410112945;4448.733076015434;4456.353828694837;4463.308187230984;4491.573300536444;4501.459824821026;4297.462817147856;4465.888352791174;4481.126180394825;4488.192125417424;4495.370648109268;3745.494358261627;4409.492908315553;4432;4445.288753799409;4448.221561226773;3454.032870796181;3747.831347589365;3872.1994272663346;3876.2441323941844;3876.8061596920325;5171.483391293918;5286.969041036999;5568.382860055767;5648.350439699446;5653.265075540195;4975.303457515951;4994.475580064113;5005.874853128665;5015.3730783652045;5036.622253331015;6193.876122477525;6199.938000619981;6205.944989351776;6226.968865155691;6272.341404152544;3938.1926705025403;4111.671066314688;4136.958630413687;4144.48193975753;4154.1068670235745;5829.650220986712;6030.758769649209;6486.972596237906;6541.411272985407;6591.7363305467725;4171.124063946605;4193.580641935782;4205.726627769185;4206.11671548972;4221.197972385298;3682.5949145593963;3950.2297052075146;3954.209158168378;4248.617624413818;4268.743875367488;4946.431160416598;4972.0801651694555;4973.303737476756;4977.477364876709;5023.6483446159145;5330.760115794798;6005.459508644243;6216.7104697758405;6250.437320698492;6267.4045965633195;1165.143619439711;1273.891719203878;1378.848326684064;1413.6889884225454;1420.666143456287;897;905.4114825363434;907.2515174980667;908;941.6986564299358;5041.621878359138;5694.629849059798;5723.370429252779;5724.8282551523525;5734.512566431817;6116.78591249313;6354.600288845538;6371.132990154357;6398.808035758934;6494.285628580852;5772.604369186824;5821.330546987066;5823.767049318065;5851.186132298916;5864.736086876101;3161.3203161320425;3224.2423030587793;3278.246003419227;3394.5926488821005;3409.403354412968;5620.325560932714;6386.275705559473;6733.562318449285;6733.663316834139;6747.730090796412;5626.831195064155;5636.2954630671165;6580.670966451658;6605.273419923805;6622.238442579069;5536.474034966672;5850.981411413056;6458.5479016469335;6497.772577959749;6598.502793519442;6167.84580385494;6398;6402.903956440564;6428.935710642833;6433.742650294123;5391.433899440659;5448.046591846491;5970.58205925581;6352.333005034405;6360.745570177326;5979.342272349951;6241.563090583615;6791.464956060795;6791.694373753094;6791.89812152828;4140.730852494578;4154.91690166201;4170.645495132826;4171.791410429527;4203;6037.822624588163;6081.270247570143;6479.740810367721;6496.285408605144;6515.8922983093935;5322.361316642027;6070.969645151746;6075.969620151871;6247.843803904757;6265.624062556079;4897.608191344755;5683.488486036276;6234.407731265523;6234.9688251558455;6245.875082498415;5867.442592953663;6081.391860813883;6086.421789929949;6088.482478989158;6096.329403765671;6169.753209871555;6174.320824709368;6210.285817130907;6215.160953271251;6240.656763877849;5763.394843541368;6143.631382117087;6150.907736383869;6188.566800324114;6211.751529938932 21:52:45 INFO - PROCESS | 1957 | |5;yui.html;668.4652278177458;743.7173873927907;753.5365750063705;753.6495529578748;759.4228386426317;14829.629259268524;14882.479113231038;15337.923310383447;15348.539543813675;15762.684746305096;12291.323977181251;14461.132332060084;14510.492132775355;14552.199629020399;14592.562223133324;730.167608925825;908.6274627402767;913.5797533134765;921.0237148622448;923.6582464488149;746.6453434618567;891.7993451473432;925.8102089071758;927.9582418791136;928.5450129436562;941.8634298026782;942.8585712143165;947.1617618407724;947.6446332625266;950.3015283766426;752.5259086775296;940.2618944128826;940.3558562384789;941.5057095025098;942.5334459442552;570.6347937320119;572.5820151289582;572.8052462162843;575.3987083497755;722.1478655186877;15231.466898658493;15278.541643750594;15286.312115954808;15296.235188240655;15301.61745956349;12545.168072447801;12844.935775321064;12846.100772945898;12862.099653024292;13093.738125237442;9617.855732164024;9825.3613515122;9957.850632240523;10362.430066346347;10597.099246563974;4121.835126594932;4165.416841642173;4271.209826182167;4288.549702281247;4375.518692943773;65921;83288;84700;84939.57530212372;85304.57347713222;9308.696782450463;9465.290103242285;9552.617895284111;9733.270004749673;9876.950615247022;20753.688694669592;20916.37250882476;20918.26786062511;20931.84874831899;21020.684689729667;6518.413342799171;7103.92896071043;7119;7123.89314160288;7351.191368949411;3977.5226972763453;3999.560048394674;4021.1756589899;4042.211768705133;4042.2521833460905;3476;3553.982230088833;3558.62634423387;3559.056849934769;3561.465780133001;538.356663786771;601.3445344574435;602.7890238416519;606.1725744358919;618.9879546940784;483.48508838087326;522.3079419768821;566.8214512428572;580.1327016110941;592.2626330218905;7748.457607967501;7856.567888766059;7885.21930673182;7901.525908445512;7951.403644726553;547.4197350808156;560.1961185698455;586.2496005113462;592.4253474130087;598.3657323237383;2891.320539673175;3268;3301.603807543062;3400.659934006629;3414.812185329781;83171.58414207891;86111;86358;87105.5644721785;87395;67923;68830.3116968834;72319.63840180871;72640.63679681568;72744.63627681827;64671.67664161649;65087.34912650908;65238.347616524174;65872.01191982123;66571.66714166399;6587.341265873374;6587.670616469253;6598.241202261609;6752.93247067523;6776.728930842711;42387.788061059495;42568.57431425646;43617.909552261444;44112;44314.335284970104;45222;57576.56058598569;58270.7086464565;60273.69863150832;60641.69679151576;9789.706308810748;9909.900900991186;10080.193584513074;10108.989101090126;10277.434741089312;9212.447253164832;10677.09244714208;10714.303570267908;10752.247342685936;10980.780384392427;9112.908870911206;10463.267571270242;10781.62264320745;10865.619703310345;11280.32318060886;10560.788784224118;11038.17213708991;11048.779024419628;11069.501872415922;11126.165537584551;10635.20235982289;10716.410597417216;10824.242303039027;11098.722531936766;11117.332960022424;9553;9560.426374417557;9606.423614583147;9748.610055597697;10032.147267482345;9350.579223935076;9531.952340238255;9542.570584323583;9606.183474404754;9645.42127472354 21:52:45 INFO - PROCESS | 1957 | __end_tp_report 21:52:45 INFO - PROCESS | 1957 | __start_cc_report 21:52:45 INFO - PROCESS | 1957 | _x_x_mozilla_cycle_collect,1334 21:52:45 INFO - PROCESS | 1957 | __end_cc_report 21:52:45 INFO - PROCESS | 1957 | __startTimestamp1460782365631__endTimestamp 21:52:45 INFO - PROCESS | 1957 | 21:52:45 INFO - PROCESS | 1957 | ------- Summary: start ------- 21:52:45 INFO - PROCESS | 1957 | Number of tests: 6 21:52:45 INFO - PROCESS | 1957 | 21:52:45 INFO - PROCESS | 1957 | [#0] dojo.html Cycles:165 Average:5669.32 Median:5821.48 stddev:626.36 (10.8%) stddev-sans-first:601.29 21:52:45 INFO - PROCESS | 1957 | Values: 3350.9 3367.9 3400.3 3420.5 3434.9 5536.2 5587.5 5596.8 5612.8 5624.9 5625.5 5663.7 5732.6 5752.1 5758.7 5100.2 5116.3 5139.3 5161.3 5283.9 5248.1 5276.3 5286.3 5293.7 5357.4 5118.3 5127.9 5204.3 5238.9 5263.4 5186.6 5197.2 5201.5 5203.3 5233.9 5130.7 5156.5 5158.2 5166.1 5198.2 5442.2 5476.5 5582.5 5613.6 5661.7 6065.0 6104.6 6107.2 6109.5 6138.9 6154.3 6206.5 6211.2 6215.5 6224.4 5602.0 5662.4 5699.5 5706.9 5773.3 6283.7 6284.7 6286.5 6305.2 6309.2 6033.9 6048.1 6054.9 6056.3 6063.5 6081.7 6130.0 6144.1 6167.9 6235.4 6048.7 6083.8 6089.8 6112.8 6116.7 6102.9 6110.9 6123.2 6129.9 6139.0 4818.0 4827.0 4838.3 4846.2 4914.5 4213.6 4299.7 4368.0 4369.7 4423.8 4893.6 5575.5 5578.7 5595.0 5602.6 6110.2 6115.9 6186.3 6214.7 6270.9 5578.2 5600.0 5610.1 5640.2 5645.0 5309.5 5328.9 5330.8 5358.7 5383.0 6184.3 6228.3 6269.9 6290.0 6314.3 6206.1 6247.3 6249.9 6253.1 6263.5 6285.6 6292.5 6300.3 6319.6 6327.7 4966.7 6031.3 6078.7 6108.4 6125.8 6181.4 6223.5 6237.0 6248.7 6251.9 5651.0 5653.4 5999.0 6016.1 6042.5 5821.5 5906.6 5974.1 6024.5 6051.0 5819.8 5834.3 5840.1 5842.0 5842.6 5970.7 5975.4 5998.2 6011.0 6064.7 5697.5 5784.2 5815.4 5825.8 5830.4 21:52:45 INFO - PROCESS | 1957 | 21:52:45 INFO - PROCESS | 1957 | [#1] ext.html Cycles:180 Average:63655.55 Median:35450.32 stddev:131978.94 (372.3%) stddev-sans-first:132264.00 21:52:45 INFO - PROCESS | 1957 | Values: 504.8 532.6 542.2 586.3 587.1 30189.7 30702.4 30730.8 30781.9 38452.8 41460.8 42219.6 42263.0 42326.8 42363.6 9399.4 9642.6 9647.9 9657.4 9657.5 8325.7 8638.8 8924.7 9724.3 11569.1 11667.5 11800.8 11801.5 11813.8 11827.6 11653.6 11782.4 11784.0 11789.1 11791.9 8600.5 8901.3 8918.9 11373.7 11731.9 39035.4 39429.6 39437.7 39567.0 39633.6 3248.0 3316.5 3326.1 3889.4 4095.1 28018.0 28402.6 28610.3 28611.0 28759.9 2660.3 2735.6 2743.0 2744.6 2837.6 441168.0 442404.0 442446.0 443617.0 444534.8 63317.0 65134.3 65174.0 65215.3 65350.7 66510.3 66868.0 66871.3 67018.7 67095.0 16394.8 16895.4 16948.5 16961.5 16974.8 11803.2 13022.6 13085.0 13106.9 13129.9 16239.9 17988.8 18333.4 18354.2 18642.4 6173.3 6725.4 7059.8 7174.6 7338.8 970.0 1002.8 1020.0 1078.6 1127.9 28070.9 29767.3 29893.7 29980.0 30428.5 1079.9 1129.4 1152.0 1210.6 1210.7 15281.8 15467.0 15501.8 15851.7 15907.4 606017.0 615066.0 615181.0 615276.0 615998.9 62393.8 62814.0 62956.4 63026.7 63449.0 1386.0 1390.6 1413.6 1491.0 1506.4 28776.0 29242.0 29650.0 29768.0 30549.5 50915.7 50943.0 50949.7 51237.5 51593.0 402853.0 404946.0 405079.0 405516.0 405567.0 39324.2 39465.6 40294.6 40298.6 40393.6 44627.6 46573.5 47172.0 48539.3 48553.0 35420.6 36027.6 36176.3 36914.3 36922.9 34016.8 34325.1 34723.0 35036.1 35057.8 34894.8 35480.0 35853.8 36138.9 36996.1 31450.8 32773.5 32800.0 32857.3 32905.0 21287.8 23819.8 24250.8 24825.6 24868.0 21:52:45 INFO - PROCESS | 1957 | 21:52:45 INFO - PROCESS | 1957 | [#2] jquery.html Cycles:180 Average:33346.76 Median:2181.00 stddev:91414.08 (4191.4%) stddev-sans-first:91637.02 21:52:45 INFO - PROCESS | 1957 | Values: 387.9 392.8 393.2 394.4 404.2 14795.4 18350.6 19107.0 20109.7 20120.6 16251.0 19287.5 19751.9 20398.0 20731.3 3976.9 4507.6 4535.8 4569.3 4572.0 4254.4 6067.0 6123.2 6124.9 6345.3 6676.7 6680.3 6684.3 6696.0 6700.9 6442.4 6551.9 6648.5 6649.2 6659.2 5519.7 5782.9 5785.0 5808.3 5811.5 25632.6 25847.4 26558.8 26691.3 26748.2 2146.8 2159.6 2202.4 2268.4 2273.7 14763.1 15060.5 15121.3 15716.0 15978.5 1738.3 1768.1 1780.4 1801.3 1918.3 110577.3 114209.4 117025.0 117690.4 118415.6 8914.6 9809.6 10711.9 10991.6 11210.9 14143.9 14153.9 14229.2 14255.1 14261.6 7264.0 7407.6 7662.4 7823.8 8649.1 7262.5 8592.0 8608.4 8729.4 8731.4 6550.3 6689.3 6709.6 6713.6 6715.2 1837.6 1865.6 1872.4 1889.4 1899.5 955.4 956.0 982.5 1008.0 1070.2 19360.2 20359.2 21110.7 21117.9 21267.8 983.1 983.3 1008.7 1083.3 1142.6 10278.7 11449.9 11466.7 11525.8 11542.4 509323.0 514955.0 517173.0 572416.0 599303.0 116348.4 137211.0 139304.0 139466.0 148309.0 1018.8 1047.4 1061.6 1064.3 1068.3 18027.5 18319.8 18442.0 18546.9 18729.3 47374.1 47964.3 48246.4 49070.8 49124.8 69873.0 71188.0 72086.0 72216.6 72248.6 6741.0 6991.6 7544.8 7997.1 8514.5 11707.9 12687.0 12728.6 12906.8 13009.9 10978.6 11251.2 11385.5 11426.0 11469.8 10652.1 10882.2 10978.8 11193.7 11221.2 6120.4 8336.7 9549.9 9938.4 11228.8 7471.0 7547.7 7599.2 7856.3 7878.4 11299.7 11363.2 11401.2 11421.9 11659.8 21:52:45 INFO - PROCESS | 1957 | 21:52:45 INFO - PROCESS | 1957 | [#3] mootools.html Cycles:180 Average:5662.26 Median:4878.73 stddev:3151.03 (64.6%) stddev-sans-first:3143.84 21:52:45 INFO - PROCESS | 1957 | Values: 1432.4 1611.2 1628.4 1904.7 2033.6 7962.0 8657.6 8891.2 8896.3 8908.2 8494.3 8717.6 8719.6 8722.1 8734.4 2192.7 2411.5 2476.5 2509.3 2518.6 2504.4 2532.3 2533.1 2533.5 2536.0 2443.9 2498.2 2499.1 2500.3 2504.6 2169.0 2417.4 2471.2 2493.2 2499.5 2446.7 2484.3 2506.1 2511.8 2511.9 8691.8 8747.1 8754.0 8770.5 8793.2 5229.1 5362.6 5417.5 5421.3 5440.3 3577.2 3582.8 3628.4 3664.8 3691.1 1039.7 1053.3 1054.9 1065.5 1065.7 11561.9 11596.7 11601.5 11608.2 11617.4 4646.1 4663.0 4853.1 4890.0 4896.6 9872.2 9891.7 9929.3 9958.4 9961.5 5364.8 5379.6 5389.1 5409.2 5413.5 4404.7 4594.0 4775.4 4928.8 4967.4 4009.2 4014.8 4089.3 4119.9 4165.0 1346.3 1364.7 1366.8 1367.9 1377.3 846.0 902.3 902.5 947.3 956.4 5549.9 5610.3 5661.3 5663.0 5827.6 455.3 455.8 462.9 471.9 472.1 3687.9 3786.3 3818.2 3852.5 3863.2 10191.6 10290.3 10554.4 10696.3 10797.0 10489.0 10641.0 10653.9 10660.9 10665.3 7308.0 7760.2 7894.6 8168.1 8236.6 5482.3 5550.4 5579.6 5645.3 5737.9 9591.9 9725.0 9788.1 9833.2 10004.2 9342.1 9872.8 9877.7 10023.9 10135.5 7209.9 7395.6 7519.7 7521.7 7539.2 8233.7 8235.6 8325.4 8334.0 8357.9 7325.2 7355.7 7399.4 7506.8 7552.6 7194.8 7202.4 7324.2 7329.4 7410.7 6632.2 6664.7 6690.1 6705.9 6713.7 4642.0 4732.4 4789.7 4846.6 4990.2 4188.9 4779.1 4868.0 4889.5 4945.2 21:52:45 INFO - PROCESS | 1957 | 21:52:45 INFO - PROCESS | 1957 | [#4] prototype.html Cycles:180 Average:5032.60 Median:5724.10 stddev:1572.21 (27.5%) stddev-sans-first:1536.12 21:52:45 INFO - PROCESS | 1957 | Values: 308.8 315.9 324.0 326.1 328.3 5845.2 5889.4 5923.2 5942.5 5946.5 5290.7 5450.2 5558.4 5763.8 5858.6 4425.8 4460.5 4464.8 4469.5 4518.0 4448.7 4456.4 4463.3 4491.6 4501.5 4297.5 4465.9 4481.1 4488.2 4495.4 3745.5 4409.5 4432.0 4445.3 4448.2 3454.0 3747.8 3872.2 3876.2 3876.8 5171.5 5287.0 5568.4 5648.4 5653.3 4975.3 4994.5 5005.9 5015.4 5036.6 6193.9 6199.9 6205.9 6227.0 6272.3 3938.2 4111.7 4137.0 4144.5 4154.1 5829.7 6030.8 6487.0 6541.4 6591.7 4171.1 4193.6 4205.7 4206.1 4221.2 3682.6 3950.2 3954.2 4248.6 4268.7 4946.4 4972.1 4973.3 4977.5 5023.6 5330.8 6005.5 6216.7 6250.4 6267.4 1165.1 1273.9 1378.8 1413.7 1420.7 897.0 905.4 907.3 908.0 941.7 5041.6 5694.6 5723.4 5724.8 5734.5 6116.8 6354.6 6371.1 6398.8 6494.3 5772.6 5821.3 5823.8 5851.2 5864.7 3161.3 3224.2 3278.2 3394.6 3409.4 5620.3 6386.3 6733.6 6733.7 6747.7 5626.8 5636.3 6580.7 6605.3 6622.2 5536.5 5851.0 6458.5 6497.8 6598.5 6167.8 6398.0 6402.9 6428.9 6433.7 5391.4 5448.0 5970.6 6352.3 6360.7 5979.3 6241.6 6791.5 6791.7 6791.9 4140.7 4154.9 4170.6 4171.8 4203.0 6037.8 6081.3 6479.7 6496.3 6515.9 5322.4 6071.0 6076.0 6247.8 6265.6 4897.6 5683.5 6234.4 6235.0 6245.9 5867.4 6081.4 6086.4 6088.5 6096.3 6169.8 6174.3 6210.3 6215.2 6240.7 5763.4 6143.6 6150.9 6188.6 6211.8 21:52:45 INFO - PROCESS | 1957 | 21:52:45 INFO - PROCESS | 1957 | [#5] yui.html Cycles:180 Average:17122.78 Median:595.40 stddev:23799.90 (3997.3%) stddev-sans-first:23834.59 21:52:45 INFO - PROCESS | 1957 | Values: 668.5 743.7 753.5 753.6 759.4 14829.6 14882.5 15337.9 15348.5 15762.7 12291.3 14461.1 14510.5 14552.2 14592.6 730.2 908.6 913.6 921.0 923.7 746.6 891.8 925.8 928.0 928.5 941.9 942.9 947.2 947.6 950.3 752.5 940.3 940.4 941.5 942.5 570.6 572.6 572.8 575.4 722.1 15231.5 15278.5 15286.3 15296.2 15301.6 12545.2 12844.9 12846.1 12862.1 13093.7 9617.9 9825.4 9957.9 10362.4 10597.1 4121.8 4165.4 4271.2 4288.5 4375.5 65921.0 83288.0 84700.0 84939.6 85304.6 9308.7 9465.3 9552.6 9733.3 9877.0 20753.7 20916.4 20918.3 20931.8 21020.7 6518.4 7103.9 7119.0 7123.9 7351.2 3977.5 3999.6 4021.2 4042.2 4042.3 3476.0 3554.0 3558.6 3559.1 3561.5 538.4 601.3 602.8 606.2 619.0 483.5 522.3 566.8 580.1 592.3 7748.5 7856.6 7885.2 7901.5 7951.4 547.4 560.2 586.2 592.4 598.4 2891.3 3268.0 3301.6 3400.7 3414.8 83171.6 86111.0 86358.0 87105.6 87395.0 67923.0 68830.3 72319.6 72640.6 72744.6 64671.7 65087.3 65238.3 65872.0 66571.7 6587.3 6587.7 6598.2 6752.9 6776.7 42387.8 42568.6 43617.9 44112.0 44314.3 45222.0 57576.6 58270.7 60273.7 60641.7 9789.7 9909.9 10080.2 10109.0 10277.4 9212.4 10677.1 10714.3 10752.2 10980.8 9112.9 10463.3 10781.6 10865.6 11280.3 10560.8 11038.2 11048.8 11069.5 11126.2 10635.2 10716.4 10824.2 11098.7 11117.3 9553.0 9560.4 9606.4 9748.6 10032.1 9350.6 9532.0 9542.6 9606.2 9645.4 21:52:45 INFO - PROCESS | 1957 | -------- Summary: end -------- 21:52:45 INFO - PROCESS | 1957 | 21:52:46 INFO - TEST-INFO | 1957: exit 0 21:52:46 INFO - TEST-OK | dromaeo_css | took 1114833ms 21:52:46 INFO - TEST-START | kraken 21:52:46 INFO - operating with platform_type : mac_ 21:52:46 INFO - Initialising browser for kraken test... 21:52:46 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpuC9J6J/profile http://localhost:49669/getInfo.html) 21:52:48 INFO - PROCESS | 1960 | __metrics Screen width/height:1600/1200 21:52:48 INFO - PROCESS | 1960 | colorDepth:24 21:52:48 INFO - PROCESS | 1960 | Browser inner width/height: 1024/689 21:52:48 INFO - PROCESS | 1960 | __metrics 21:52:48 INFO - PROCESS | 1960 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:52:48 ERROR - PROCESS | 1960 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:52:49 INFO - TEST-INFO | 1960: exit 0 21:52:49 INFO - Browser initialized. 21:52:49 INFO - Running cycle 1/1 for kraken test... 21:52:49 INFO - TEST-INFO | started process 1962 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpuC9J6J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:53:01 INFO - PROCESS | 1962 | RSS: Main: 237461504 21:53:01 INFO - PROCESS | 1962 | 21:53:03 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49669/tests/kraken/driver.html?testName=audio-beat-detection) 21:53:04 INFO - PROCESS | 1962 | RSS: Main: 286003200 21:53:04 INFO - PROCESS | 1962 | 21:53:07 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49669/tests/kraken/driver.html?testName=audio-dft) 21:53:08 INFO - PROCESS | 1962 | RSS: Main: 299536384 21:53:08 INFO - PROCESS | 1962 | 21:53:11 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49669/tests/kraken/driver.html?testName=audio-fft) 21:53:12 INFO - PROCESS | 1962 | RSS: Main: 384438272 21:53:12 INFO - PROCESS | 1962 | 21:53:14 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49669/tests/kraken/driver.html?testName=audio-oscillator) 21:53:14 INFO - PROCESS | 1962 | RSS: Main: 287604736 21:53:14 INFO - PROCESS | 1962 | 21:53:17 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49669/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:53:17 INFO - PROCESS | 1962 | RSS: Main: 291778560 21:53:17 INFO - PROCESS | 1962 | 21:53:20 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49669/tests/kraken/driver.html?testName=imaging-darkroom) 21:53:21 INFO - PROCESS | 1962 | RSS: Main: 330801152 21:53:21 INFO - PROCESS | 1962 | 21:53:23 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49669/tests/kraken/driver.html?testName=imaging-desaturate) 21:53:24 INFO - PROCESS | 1962 | RSS: Main: 329617408 21:53:24 INFO - PROCESS | 1962 | 21:53:27 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49669/tests/kraken/driver.html?testName=json-parse-financial) 21:53:27 INFO - PROCESS | 1962 | RSS: Main: 327327744 21:53:27 INFO - PROCESS | 1962 | 21:53:29 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49669/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:53:30 INFO - PROCESS | 1962 | RSS: Main: 320012288 21:53:30 INFO - PROCESS | 1962 | 21:53:32 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:53:32 INFO - PROCESS | 1962 | RSS: Main: 326225920 21:53:32 INFO - PROCESS | 1962 | 21:53:34 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:53:35 INFO - PROCESS | 1962 | RSS: Main: 329445376 21:53:35 INFO - PROCESS | 1962 | 21:53:38 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:53:39 INFO - PROCESS | 1962 | RSS: Main: 334647296 21:53:39 INFO - PROCESS | 1962 | 21:53:41 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:53:42 INFO - PROCESS | 1962 | RSS: Main: 332091392 21:53:42 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49669/tests/kraken/driver.html?testName=ai-astar) 21:53:44 INFO - PROCESS | 1962 | RSS: Main: 396935168 21:53:44 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | __start_tp_report 21:53:44 INFO - PROCESS | 1962 | _x_x_mozilla_page_load 21:53:44 INFO - PROCESS | 1962 | _x_x_mozilla_page_load_details 21:53:44 INFO - PROCESS | 1962 | |i|pagename|runs| 21:53:44 INFO - PROCESS | 1962 | |0;ai-astar;113;111;113;112;154;157;121;112;111;112 21:53:44 INFO - PROCESS | 1962 | |1;audio-beat-detection;158;123;143;169;121;141;139;119;169;120 21:53:44 INFO - PROCESS | 1962 | |2;audio-dft;185;189;188;187;183;186;185;186;186;186 21:53:44 INFO - PROCESS | 1962 | |3;audio-fft;73;74;88;90;55;59;61;78;83;55 21:53:44 INFO - PROCESS | 1962 | |4;audio-oscillator;89;100;142;75;104;108;161;78;77;127 21:53:44 INFO - PROCESS | 1962 | |5;imaging-gaussian-blur;90;87;91;87;90;86;89;88;90;87 21:53:44 INFO - PROCESS | 1962 | |6;imaging-darkroom;95;95;95;95;95;95;95;96;96;95 21:53:44 INFO - PROCESS | 1962 | |7;imaging-desaturate;85;85;85;86;84;84;84;85;85;83 21:53:44 INFO - PROCESS | 1962 | |8;json-parse-financial;59;61;60;57;58;58;59;79;59;57 21:53:44 INFO - PROCESS | 1962 | |9;json-stringify-tinderbox;63;64;71;76;70;69;68;69;68;70 21:53:44 INFO - PROCESS | 1962 | |10;stanford-crypto-aes;82;83;82;81;82;81;79;81;82;83 21:53:44 INFO - PROCESS | 1962 | |11;stanford-crypto-ccm;154;147;147;159;165;146;142;138;178;138 21:53:44 INFO - PROCESS | 1962 | |12;stanford-crypto-pbkdf2;179;209;184;167;183;167;169;170;171;167 21:53:44 INFO - PROCESS | 1962 | |13;stanford-crypto-sha256-iterative;70;84;92;68;63;89;93;93;87;66 21:53:44 INFO - PROCESS | 1962 | __end_tp_report 21:53:44 INFO - PROCESS | 1962 | __start_cc_report 21:53:44 INFO - PROCESS | 1962 | _x_x_mozilla_cycle_collect,1981 21:53:44 INFO - PROCESS | 1962 | __end_cc_report 21:53:44 INFO - PROCESS | 1962 | __startTimestamp1460782424717__endTimestamp 21:53:44 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | ------- Summary: start ------- 21:53:44 INFO - PROCESS | 1962 | Number of tests: 14 21:53:44 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | [#0] ai-astar Cycles:10 Average:121.60 Median:113.00 stddev:18.11 (16.0%) stddev-sans-first:18.94 21:53:44 INFO - PROCESS | 1962 | Values: 113.0 111.0 113.0 112.0 154.0 157.0 121.0 112.0 111.0 112.0 21:53:44 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:142.00 stddev:19.74 (13.9%) stddev-sans-first:19.86 21:53:44 INFO - PROCESS | 1962 | Values: 158.0 123.0 143.0 169.0 121.0 141.0 139.0 119.0 169.0 120.0 21:53:44 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | [#2] audio-dft Cycles:10 Average:186.10 Median:186.00 stddev:1.66 (0.9%) stddev-sans-first:1.72 21:53:44 INFO - PROCESS | 1962 | Values: 185.0 189.0 188.0 187.0 183.0 186.0 185.0 186.0 186.0 186.0 21:53:44 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | [#3] audio-fft Cycles:10 Average:71.60 Median:76.00 stddev:13.37 (17.6%) stddev-sans-first:14.17 21:53:44 INFO - PROCESS | 1962 | Values: 73.0 74.0 88.0 90.0 55.0 59.0 61.0 78.0 83.0 55.0 21:53:44 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | [#4] audio-oscillator Cycles:10 Average:106.10 Median:118.00 stddev:29.21 (24.8%) stddev-sans-first:30.32 21:53:44 INFO - PROCESS | 1962 | Values: 89.0 100.0 142.0 75.0 104.0 108.0 161.0 78.0 77.0 127.0 21:53:44 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | [#5] imaging-gaussian-blur Cycles:10 Average:88.50 Median:89.50 stddev:1.72 (1.9%) stddev-sans-first:1.73 21:53:44 INFO - PROCESS | 1962 | Values: 90.0 87.0 91.0 87.0 90.0 86.0 89.0 88.0 90.0 87.0 21:53:44 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | [#6] imaging-darkroom Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 21:53:44 INFO - PROCESS | 1962 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 21:53:44 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:0.84 (1.0%) stddev-sans-first:0.88 21:53:44 INFO - PROCESS | 1962 | Values: 85.0 85.0 85.0 86.0 84.0 84.0 84.0 85.0 85.0 83.0 21:53:44 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | [#8] json-parse-financial Cycles:10 Average:60.70 Median:59.00 stddev:6.55 (11.1%) stddev-sans-first:6.92 21:53:44 INFO - PROCESS | 1962 | Values: 59.0 61.0 60.0 57.0 58.0 58.0 59.0 79.0 59.0 57.0 21:53:44 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | [#9] json-stringify-tinderbox Cycles:10 Average:68.80 Median:69.50 stddev:3.61 (5.2%) stddev-sans-first:3.17 21:53:44 INFO - PROCESS | 1962 | Values: 63.0 64.0 71.0 76.0 70.0 69.0 68.0 69.0 68.0 70.0 21:53:44 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:82.00 stddev:1.17 (1.4%) stddev-sans-first:1.24 21:53:44 INFO - PROCESS | 1962 | Values: 82.0 83.0 82.0 81.0 82.0 81.0 79.0 81.0 82.0 83.0 21:53:44 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | [#11] stanford-crypto-ccm Cycles:10 Average:151.40 Median:150.50 stddev:12.79 (8.5%) stddev-sans-first:13.53 21:53:44 INFO - PROCESS | 1962 | Values: 154.0 147.0 147.0 159.0 165.0 146.0 142.0 138.0 178.0 138.0 21:53:44 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.60 Median:175.00 stddev:13.17 (7.5%) stddev-sans-first:13.94 21:53:44 INFO - PROCESS | 1962 | Values: 179.0 209.0 184.0 167.0 183.0 167.0 169.0 170.0 171.0 167.0 21:53:44 INFO - PROCESS | 1962 | 21:53:44 INFO - PROCESS | 1962 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.50 Median:88.00 stddev:12.27 (13.9%) stddev-sans-first:12.41 21:53:44 INFO - PROCESS | 1962 | Values: 70.0 84.0 92.0 68.0 63.0 89.0 93.0 93.0 87.0 66.0 21:53:44 INFO - PROCESS | 1962 | -------- Summary: end -------- 21:53:44 INFO - PROCESS | 1962 | 21:53:45 INFO - TEST-INFO | 1962: exit 0 21:53:45 INFO - TEST-OK | kraken | took 59304ms 21:53:45 INFO - SUITE-END | took 1174s 21:53:45 INFO - Completed test suite (00:19:34) 21:53:45 INFO - javascript benchmark 21:53:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3350.8994730158097, 3367.932641347173, 3400.2859399526096, 3420.4527275635887, 3434.9484757728637, 5536.2249285100115, 5587.525060369869, 5596.804111856086, 5612.803551875675, 5624.91562626561, 5625.465580769831, 5663.66018038918, 5732.5987180897355, 5752.108423194411, 5758.7408566614495, 5100.183970564692, 5116.283720279173, 5139.331886854704, 5161.329027226455, 5283.894322113555, 5248.107821670325, 5276.287701160332, 5286.31277933868, 5293.68237905725, 5357.4106848246665, 5118.283440318359, 5127.948720512822, 5204.29741984831, 5238.895222095556, 5263.4473380295285, 5186.559142472895, 5197.22041693749, 5201.479852014806, 5203.271541984125, 5233.895322093536, 5130.743462826844, 5156.535911767959, 5158.226266060084, 5166.095933211673, 5198.220266959948, 5442.2380866678695, 5476.534494567926, 5582.497575218209, 5613.578981576357, 5661.745221465044, 6064.96967515164, 6104.641056839901, 6107.236595425572, 6109.511239100906, 6138.8772224555705, 6154.261488621394, 6206.503479721611, 6211.192544969125, 6215.502759779207, 6224.408681175281, 5601.991641504527, 5662.37713851476, 5699.544036477072, 5706.914396284101, 5773.278340207474, 6283.717232724538, 6284.717187726564, 6286.497080233662, 6305.180326557518, 6309.242890853127, 6033.909491357633, 6048.092786082123, 6054.878902422015, 6056.273247210363, 6063.514918806485, 6081.695915204222, 6130.049842274455, 6144.064619644798, 6167.876642467215, 6235.376462353819, 6048.667323297261, 6083.756649733961, 6089.817305480843, 6112.786052488142, 6116.694165291718, 6102.913431175913, 6110.867747202496, 6123.1606109212, 6129.938700612936, 6139.0, 4818.0, 4826.95173048265, 4838.346823178826, 4846.248831431134, 4914.483979182206, 4213.578642135823, 4299.720518166309, 4367.956320436755, 4369.650427965755, 4423.800928958204, 4893.559579637849, 5575.52608028314, 5578.665280083208, 5594.9720251398485, 5602.635828671123, 6110.205673262448, 6115.877682446415, 6186.288576813724, 6214.689265536705, 6270.9372906270355, 5578.24693666358, 5599.972000139973, 5610.13042978339, 5640.153976903497, 5645.040343141676, 5309.495597918191, 5328.946710532922, 5330.840074797762, 5358.705271210121, 5383.0041442333295, 6184.288806787097, 6228.34602366745, 6269.937300626935, 6290.0, 6314.336994615683, 6206.10011548332, 6247.31279559239, 6249.906251406142, 6253.1245625613365, 6263.467605253604, 6285.59143655661, 6292.4966002720585, 6300.306966233802, 6319.557630965788, 6327.683615819283, 4966.6771659842, 6031.306399764083, 6078.7264573093435, 6108.372425856702, 6125.815438131279, 6181.35095814933, 6223.533235007302, 6237.002079667232, 6248.718807653758, 6251.937480625135, 5650.971745141413, 5653.434656534479, 5999.040153575407, 6016.127661489147, 6042.456178943916, 5821.476067153976, 5906.616069955439, 5974.074018527135, 6024.518038556817, 6050.969745151246, 5819.76720931158, 5834.329052159056, 5840.094785308284, 5842.0, 5842.591018628656, 5970.671613061323, 5975.43233392827, 5998.190244316961, 6010.969945150421, 6064.666443345658, 5697.515711164431, 5784.190213370048, 5815.360310365941, 5825.76696932118, 5830.387809280134], "value": 5629.950722777796, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.8233118408557, 532.5712801195036, 542.1650657986701, 586.2613107484568, 587.1486344800041, 30189.698103018964, 30702.38595228094, 30730.846345768266, 30781.92263270786, 38452.80773596135, 41460.79269603648, 42219.577804221946, 42263.0, 42326.7883660582, 42363.576364236345, 9399.436033837974, 9642.614295428175, 9647.903520964806, 9657.4205547667, 9657.46883921384, 8325.66697332106, 8638.827223455528, 8924.687635932743, 9724.319297649168, 11569.13231507636, 11667.474963626615, 11800.7639847203, 11801.468933897995, 11813.822792658117, 11827.645170644895, 11653.592124275652, 11782.410879455992, 11783.999999999956, 11789.056875449944, 11791.941040294743, 8600.483970961763, 8901.287896968292, 8918.866217006751, 11373.658790236306, 11731.94134029333, 39035.41446878299, 39429.60570394288, 39437.67591375156, 39567.0, 39633.60366396328, 3248.0, 3316.502524621302, 3326.101952472819, 3889.4165875118674, 4095.1195492968855, 28018.0, 28402.587220697726, 28610.284742881388, 28611.0, 28759.85620071907, 2660.348214687395, 2735.5896615507636, 2742.9588556171675, 2744.588311753233, 2837.645294338208, 441168.0, 442404.0, 442446.0, 443617.0, 444534.77732611453, 63317.0, 65134.34865651378, 65174.0, 65215.34784652188, 65350.67324663441, 66510.33489665139, 66868.0, 66871.33128668748, 67018.66490667516, 67095.0, 16394.83605163933, 16895.408660697063, 16948.491545253662, 16961.49115526536, 16974.83025169757, 11803.17377783564, 13022.609321720363, 13085.0, 13106.934465327613, 13129.949604031655, 16239.944403613727, 17988.820111798977, 18333.3583324583, 18354.174062167236, 18642.44072677822, 6173.302341856025, 6725.394714475623, 7059.82350441243, 7174.569525828467, 7338.816529586697, 969.9903000970041, 1002.7643503776607, 1019.9541020654088, 1078.5847448732138, 1127.9097672186206, 28070.85964570205, 29767.25581860471, 29893.701062989527, 29980.0, 30428.54357184644, 1079.9460026998618, 1129.3562669278597, 1152.0495591137346, 1210.6489118155812, 1210.7033776724759, 15281.847181528265, 15466.994645348017, 15501.752245924277, 15851.682966340608, 15907.443239486794, 606017.0, 615066.0, 615181.0, 615276.0, 615998.920005406, 62393.752124957246, 62814.0, 62956.37043629597, 63026.73946521136, 63449.0, 1385.9514916977857, 1390.5967269491734, 1413.5900588829124, 1491.0382803091945, 1506.3974410235774, 28776.0, 29242.0, 29650.0, 29768.0, 30549.54175687411, 50915.74542127266, 50943.0, 50949.7452512735, 51237.48762512327, 51593.0, 402853.0, 404946.0, 405079.0, 405516.0, 405567.0, 39324.213515730095, 39465.60534394619, 40294.59705403026, 40298.59701402948, 40393.59606404016, 44627.55372446234, 46573.50698697544, 47172.0, 48539.27191092207, 48553.02893942031, 35420.645793541735, 36027.63972360243, 36176.27647447089, 36914.26171476609, 36922.892313230645, 34016.82991585026, 34325.141871453416, 34723.0, 35036.12409689676, 35057.82471087628, 34894.825525872206, 35480.0, 35853.82073089618, 36138.91583252507, 36996.07509812276, 31450.84274578704, 32773.50839737358, 32800.0, 32857.34285314328, 32905.0, 21287.787122128582, 23819.761802381756, 24250.832131504758, 24825.62761558614, 24868.0], "value": 19972.07083463183, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.9476270703455, 392.80752431308633, 393.2371199872248, 394.3690095846646, 404.21178701532, 14795.408183672667, 18350.632987340246, 19107.0, 20109.698354524695, 20120.597588048247, 16251.0, 19287.517812054706, 19751.913644749533, 20398.0, 20731.274405395816, 3976.860809871655, 4507.639388848901, 4535.7958891849785, 4569.337446070318, 4571.9542804571865, 4254.4043833863125, 6067.0, 6123.2039834821635, 6124.938750612481, 6345.270293916213, 6676.666166691671, 6680.3319668033055, 6684.2647308795995, 6696.0, 6700.899486507707, 6442.4201821835795, 6551.901721474182, 6648.534602577822, 6649.235337936151, 6659.200895892475, 5519.668819870821, 5782.9421705782825, 5784.971075144639, 5808.30300363959, 5811.506021988136, 25632.615510767166, 25847.35381615456, 26558.804853781625, 26691.332716682045, 26748.19755407341, 2146.8497205195645, 2159.6112699714045, 2202.394341556069, 2268.3648578398074, 2273.715785526809, 14763.114213147246, 15060.548183554401, 15121.319540620698, 15716.0, 15978.520644380687, 1738.3394310162057, 1768.142450911307, 1780.3501721871553, 1801.2704854533936, 1918.3094086128983, 110577.34133987916, 114209.42895285554, 117025.0, 117690.41154794171, 118415.63168736576, 8914.55427228636, 9809.558569864374, 10711.892881071246, 10991.560337586567, 11210.887891120985, 14143.929280353737, 14153.858461415253, 14229.217393043275, 14255.144691318346, 14261.572152835432, 7263.963680181565, 7407.555546667218, 7662.390897911389, 7823.843523129505, 8649.091845356274, 7262.491625586264, 8592.011918629438, 8608.354373421893, 8729.388942774072, 8731.388802783744, 6550.3449655035065, 6689.331066893272, 6709.563878347892, 6713.56361836479, 6715.1941766988275, 1837.5865430278081, 1865.5522674558008, 1872.4382685194387, 1889.4426144287468, 1899.5346140195463, 955.3646824861528, 955.9617615295449, 982.5283863745442, 1007.9848802267971, 1070.2240875365299, 19360.225590976203, 20359.245554373432, 21110.683339749918, 21117.894410528155, 21267.78732212689, 983.1004630762915, 983.3264214013424, 1008.7478130467383, 1083.2525557365393, 1142.6457798082622, 10278.743031424274, 11449.942750286362, 11466.656000320003, 11525.827112593317, 11542.365169915734, 509323.0, 514955.0, 517173.0, 572416.0, 599303.0, 116348.41825790817, 137211.0, 139304.0, 139466.0, 148309.0, 1018.8267994440963, 1047.3925123428394, 1061.6443491430457, 1064.292245656645, 1068.3056013591072, 18027.549311267325, 18319.81680183181, 18442.0, 18546.907265463586, 18729.344472943383, 47374.05251895012, 47964.280535791295, 48246.40624298377, 49070.75464622654, 49124.75437622789, 69873.0, 71188.0, 72086.0, 72216.63891680718, 72248.63875680589, 6741.0, 6991.615461149686, 7544.773656790305, 7997.120316765035, 8514.531700756517, 11707.941460292644, 12687.0, 12728.554500592434, 12906.806397904227, 13009.934950325187, 10978.615748448767, 11251.15616328795, 11385.487653055798, 11426.0, 11469.827952580552, 10652.09457196147, 10882.238243322892, 10978.835317470404, 11193.66419007431, 11221.158413118885, 6120.418560236771, 8336.749897503085, 9549.949505554245, 9938.403695778277, 11228.775424491629, 7471.0, 7547.73582924595, 7599.202083781344, 7856.304179469838, 7878.369730421668, 11299.661010169708, 11363.204575679954, 11401.20191586581, 11421.942890285827, 11659.825102623638], "value": 9595.95961801423, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.448507324681, 1611.194402798601, 1628.4463282483957, 1904.7238150468183, 2033.5831154613306, 7962.044554653443, 8657.61040753166, 8891.244244239231, 8896.332775041865, 8908.242799362053, 8494.320454363651, 8717.607707653155, 8719.5640217989, 8722.127787221274, 8734.432261902972, 2192.7368715754133, 2411.5176964607062, 2476.4551798604325, 2509.297396728919, 2518.559252130879, 2504.3864253257975, 2532.3036165054573, 2533.1134103063964, 2533.5312967101054, 2536.0, 2443.8900249488725, 2498.2005758157397, 2499.0628514307136, 2500.2624225853415, 2504.5992641177413, 2168.9783102169013, 2417.4439878827793, 2471.1721573272885, 2493.2021753039035, 2499.512595043967, 2446.694163229596, 2484.3043947694673, 2506.1103308325646, 2511.836730612504, 2511.899524019037, 8691.826163476759, 8747.081556436546, 8754.0, 8770.473771573663, 8793.20861122502, 5229.111051121318, 5362.570994320445, 5417.485338892839, 5421.2952316199035, 5440.319960004999, 3577.2487777566744, 3582.8387722552548, 3628.4375921732176, 3664.76179048361, 3691.1141326081815, 1039.7296702857236, 1053.3153450257316, 1054.8997845204694, 1065.477915821242, 1065.7495488560182, 11561.942190289079, 11596.710082247926, 11601.477933493014, 11608.245464044894, 11617.361045142514, 4646.140464014168, 4662.95337046632, 4853.102176097434, 4889.975550122226, 4896.559309662076, 9872.210223182128, 9891.7032489024, 9929.25530585209, 9958.352707074162, 9961.452120133463, 5364.8122315718765, 5379.569634429236, 5389.137737961907, 5409.188621706697, 5413.458654134633, 4404.7357158570585, 4593.977030114828, 4775.379200703956, 4928.778204980785, 4967.428745694291, 4009.1580768038457, 4014.8119274995197, 4089.26393249218, 4119.938200926988, 4165.042040330741, 1346.3470216944775, 1364.6929440875724, 1366.774482210444, 1367.8700523450257, 1377.2769296119618, 845.966161353539, 902.3007169443603, 902.5397047505819, 947.2895328503622, 956.3598428694821, 5549.878624443637, 5610.298712660918, 5661.264035675418, 5662.971685141548, 5827.562932780059, 455.3238440915238, 455.84501269568506, 462.88427893026744, 471.94808571057155, 472.1030042918482, 3687.8711786041245, 3786.3184626767447, 3818.236352729465, 3852.4799152114665, 3863.188730366598, 10191.643292484727, 10290.279680422298, 10554.366737995744, 10696.304740192018, 10797.028267455968, 10489.0, 10641.0, 10653.9467302663, 10660.946695266473, 10665.306755061052, 7307.976883236352, 7760.223977602194, 7894.64474098667, 8168.142345053802, 8236.629351679188, 5482.28730265079, 5550.389457159628, 5579.609427340048, 5645.294338207724, 5737.8565535861935, 9591.856122158033, 9725.0, 9788.119069283799, 9833.213342932406, 10004.199664027006, 9342.065793420603, 9872.802543949225, 9877.654282100091, 10023.949880250551, 10135.543900524346, 7209.855802883808, 7395.556266624019, 7519.661615227437, 7521.661525231487, 7539.1706912238515, 8233.670653173805, 8235.629396677285, 8325.375596830141, 8334.0, 8357.874631880648, 7325.230850760593, 7355.742549010759, 7399.445041622008, 7506.8498630026, 7552.584607846621, 7194.820129496805, 7202.387797037101, 7324.157721862054, 7329.377002954809, 7410.6665200065045, 6632.2372927114, 6664.66676666155, 6690.096836926952, 6705.932940670531, 6713.6643167842385, 4642.0483800820075, 4732.432108146907, 4789.712617242976, 4846.612271018381, 4990.15167421532, 4188.91622167561, 4779.139754844162, 4867.95132048675, 4889.511048895082, 4945.18404463274], "value": 4514.219496873935, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.8239703369081, 315.9273367125561, 323.98380080995946, 326.0528165678704, 328.2876158735544, 5845.152452894333, 5889.440503152199, 5923.229980102586, 5942.46329038058, 5946.464818166364, 5290.68255904646, 5450.209719590657, 5558.44415558444, 5763.798267060653, 5858.589898707081, 4425.8229670813125, 4460.50934397216, 4464.776761161929, 4469.4636643602735, 4517.977410112945, 4448.733076015434, 4456.353828694837, 4463.308187230984, 4491.573300536444, 4501.459824821026, 4297.462817147856, 4465.888352791174, 4481.126180394825, 4488.192125417424, 4495.370648109268, 3745.494358261627, 4409.492908315553, 4432.0, 4445.288753799409, 4448.221561226773, 3454.032870796181, 3747.831347589365, 3872.1994272663346, 3876.2441323941844, 3876.8061596920325, 5171.483391293918, 5286.969041036999, 5568.382860055767, 5648.350439699446, 5653.265075540195, 4975.303457515951, 4994.475580064113, 5005.874853128665, 5015.3730783652045, 5036.622253331015, 6193.876122477525, 6199.938000619981, 6205.944989351776, 6226.968865155691, 6272.341404152544, 3938.1926705025403, 4111.671066314688, 4136.958630413687, 4144.48193975753, 4154.1068670235745, 5829.650220986712, 6030.758769649209, 6486.972596237906, 6541.411272985407, 6591.7363305467725, 4171.124063946605, 4193.580641935782, 4205.726627769185, 4206.11671548972, 4221.197972385298, 3682.5949145593963, 3950.2297052075146, 3954.209158168378, 4248.617624413818, 4268.743875367488, 4946.431160416598, 4972.0801651694555, 4973.303737476756, 4977.477364876709, 5023.6483446159145, 5330.760115794798, 6005.459508644243, 6216.7104697758405, 6250.437320698492, 6267.4045965633195, 1165.143619439711, 1273.891719203878, 1378.848326684064, 1413.6889884225454, 1420.666143456287, 897.0, 905.4114825363434, 907.2515174980667, 908.0, 941.6986564299358, 5041.621878359138, 5694.629849059798, 5723.370429252779, 5724.8282551523525, 5734.512566431817, 6116.78591249313, 6354.600288845538, 6371.132990154357, 6398.808035758934, 6494.285628580852, 5772.604369186824, 5821.330546987066, 5823.767049318065, 5851.186132298916, 5864.736086876101, 3161.3203161320425, 3224.2423030587793, 3278.246003419227, 3394.5926488821005, 3409.403354412968, 5620.325560932714, 6386.275705559473, 6733.562318449285, 6733.663316834139, 6747.730090796412, 5626.831195064155, 5636.2954630671165, 6580.670966451658, 6605.273419923805, 6622.238442579069, 5536.474034966672, 5850.981411413056, 6458.5479016469335, 6497.772577959749, 6598.502793519442, 6167.84580385494, 6398.0, 6402.903956440564, 6428.935710642833, 6433.742650294123, 5391.433899440659, 5448.046591846491, 5970.58205925581, 6352.333005034405, 6360.745570177326, 5979.342272349951, 6241.563090583615, 6791.464956060795, 6791.694373753094, 6791.89812152828, 4140.730852494578, 4154.91690166201, 4170.645495132826, 4171.791410429527, 4203.0, 6037.822624588163, 6081.270247570143, 6479.740810367721, 6496.285408605144, 6515.8922983093935, 5322.361316642027, 6070.969645151746, 6075.969620151871, 6247.843803904757, 6265.624062556079, 4897.608191344755, 5683.488486036276, 6234.407731265523, 6234.9688251558455, 6245.875082498415, 5867.442592953663, 6081.391860813883, 6086.421789929949, 6088.482478989158, 6096.329403765671, 6169.753209871555, 6174.320824709368, 6210.285817130907, 6215.160953271251, 6240.656763877849, 5763.394843541368, 6143.631382117087, 6150.907736383869, 6188.566800324114, 6211.751529938932], "value": 4511.251321545436, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.4652278177458, 743.7173873927907, 753.5365750063705, 753.6495529578748, 759.4228386426317, 14829.629259268524, 14882.479113231038, 15337.923310383447, 15348.539543813675, 15762.684746305096, 12291.323977181251, 14461.132332060084, 14510.492132775355, 14552.199629020399, 14592.562223133324, 730.167608925825, 908.6274627402767, 913.5797533134765, 921.0237148622448, 923.6582464488149, 746.6453434618567, 891.7993451473432, 925.8102089071758, 927.9582418791136, 928.5450129436562, 941.8634298026782, 942.8585712143165, 947.1617618407724, 947.6446332625266, 950.3015283766426, 752.5259086775296, 940.2618944128826, 940.3558562384789, 941.5057095025098, 942.5334459442552, 570.6347937320119, 572.5820151289582, 572.8052462162843, 575.3987083497755, 722.1478655186877, 15231.466898658493, 15278.541643750594, 15286.312115954808, 15296.235188240655, 15301.61745956349, 12545.168072447801, 12844.935775321064, 12846.100772945898, 12862.099653024292, 13093.738125237442, 9617.855732164024, 9825.3613515122, 9957.850632240523, 10362.430066346347, 10597.099246563974, 4121.835126594932, 4165.416841642173, 4271.209826182167, 4288.549702281247, 4375.518692943773, 65921.0, 83288.0, 84700.0, 84939.57530212372, 85304.57347713222, 9308.696782450463, 9465.290103242285, 9552.617895284111, 9733.270004749673, 9876.950615247022, 20753.688694669592, 20916.37250882476, 20918.26786062511, 20931.84874831899, 21020.684689729667, 6518.413342799171, 7103.92896071043, 7119.0, 7123.89314160288, 7351.191368949411, 3977.5226972763453, 3999.560048394674, 4021.1756589899, 4042.211768705133, 4042.2521833460905, 3476.0, 3553.982230088833, 3558.62634423387, 3559.056849934769, 3561.465780133001, 538.356663786771, 601.3445344574435, 602.7890238416519, 606.1725744358919, 618.9879546940784, 483.48508838087326, 522.3079419768821, 566.8214512428572, 580.1327016110941, 592.2626330218905, 7748.457607967501, 7856.567888766059, 7885.21930673182, 7901.525908445512, 7951.403644726553, 547.4197350808156, 560.1961185698455, 586.2496005113462, 592.4253474130087, 598.3657323237383, 2891.320539673175, 3268.0, 3301.603807543062, 3400.659934006629, 3414.812185329781, 83171.58414207891, 86111.0, 86358.0, 87105.5644721785, 87395.0, 67923.0, 68830.3116968834, 72319.63840180871, 72640.63679681568, 72744.63627681827, 64671.67664161649, 65087.34912650908, 65238.347616524174, 65872.01191982123, 66571.66714166399, 6587.341265873374, 6587.670616469253, 6598.241202261609, 6752.93247067523, 6776.728930842711, 42387.788061059495, 42568.57431425646, 43617.909552261444, 44112.0, 44314.335284970104, 45222.0, 57576.56058598569, 58270.7086464565, 60273.69863150832, 60641.69679151576, 9789.706308810748, 9909.900900991186, 10080.193584513074, 10108.989101090126, 10277.434741089312, 9212.447253164832, 10677.09244714208, 10714.303570267908, 10752.247342685936, 10980.780384392427, 9112.908870911206, 10463.267571270242, 10781.62264320745, 10865.619703310345, 11280.32318060886, 10560.788784224118, 11038.17213708991, 11048.779024419628, 11069.501872415922, 11126.165537584551, 10635.20235982289, 10716.410597417216, 10824.242303039027, 11098.722531936766, 11117.332960022424, 9553.0, 9560.426374417557, 9606.423614583147, 9748.610055597697, 10032.147267482345, 9350.579223935076, 9531.952340238255, 9542.570584323583, 9606.183474404754, 9645.42127472354], "value": 6607.240201631436, "unit": "score"}], "name": "dromaeo_css", "value": 7249.8073705997695, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 113.0, 112.0, 154.0, 157.0, 121.0, 112.0, 111.0, 112.0], "value": 121.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 123.0, 143.0, 169.0, 121.0, 141.0, 139.0, 119.0, 169.0, 120.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 189.0, 188.0, 187.0, 183.0, 186.0, 185.0, 186.0, 186.0, 186.0], "value": 186.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 88.0, 90.0, 55.0, 59.0, 61.0, 78.0, 83.0, 55.0], "value": 71.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 100.0, 142.0, 75.0, 104.0, 108.0, 161.0, 78.0, 77.0, 127.0], "value": 106.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 87.0, 90.0, 86.0, 89.0, 88.0, 90.0, 87.0], "value": 88.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 86.0, 84.0, 84.0, 84.0, 85.0, 85.0, 83.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 60.0, 57.0, 58.0, 58.0, 59.0, 79.0, 59.0, 57.0], "value": 60.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 71.0, 76.0, 70.0, 69.0, 68.0, 69.0, 68.0, 70.0], "value": 68.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 82.0, 81.0, 82.0, 81.0, 79.0, 81.0, 82.0, 83.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 147.0, 147.0, 159.0, 165.0, 146.0, 142.0, 138.0, 178.0, 138.0], "value": 151.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 209.0, 184.0, 167.0, 183.0, 167.0, 169.0, 170.0, 171.0, 167.0], "value": 176.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 84.0, 92.0, 68.0, 63.0, 89.0, 93.0, 93.0, 87.0, 66.0], "value": 80.5, "unit": "score"}], "name": "kraken", "value": 1513.5, "alertThreshold": 2.0}]} 21:53:45 INFO - Return code: 0 21:53:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:53:45 ERROR - # TBPL SUCCESS # 21:53:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:53:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:53:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:53:46 INFO - 2016-04-15 21:53:46.010 screenresolution[1966:12553] starting screenresolution argv=screenresolution get 21:53:46 INFO - 2016-04-15 21:53:46.013 screenresolution[1966:12553] Display 0: 1600x1200x32@60 21:53:46 INFO - 2016-04-15 21:53:46.019 screenresolution[1967:12556] starting screenresolution argv=screenresolution list 21:53:46 INFO - Available Modes on Display 0 21:53:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:53:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:53:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:53:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:53:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:53:46 INFO - Intel Iris: 21:53:46 INFO - Chipset Model: Intel Iris 21:53:46 INFO - Type: GPU 21:53:46 INFO - Bus: Built-In 21:53:46 INFO - VRAM (Dynamic, Max): 1536 MB 21:53:46 INFO - Vendor: Intel (0x8086) 21:53:46 INFO - Device ID: 0x0a2e 21:53:46 INFO - Revision ID: 0x0009 21:53:46 INFO - Displays: 21:53:46 INFO - Display: 21:53:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:53:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:53:46 INFO - Display Serial Number: 1600x1200 60 21:53:46 INFO - Main Display: Yes 21:53:46 INFO - Mirror: Off 21:53:46 INFO - Online: Yes 21:53:46 INFO - Rotation: Supported 21:53:46 INFO - Return code: 0 21:53:46 INFO - Running post-action listener: _resource_record_post_action 21:53:46 INFO - Running post-run listener: _resource_record_post_run 21:53:47 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 102400; Write bytes: 690679296; Read time: 4; Write time: 1992 21:53:47 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 394035712; Write bytes: 388429824; Read time: 29582; Write time: 701 21:53:47 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 102400; Write bytes: 293041664; Read time: 4; Write time: 1261 21:53:47 INFO - Running post-run listener: _upload_blobber_files 21:53:47 INFO - Blob upload gear active. 21:53:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:53:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:53:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:53:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:53:47 INFO - (blobuploader) - INFO - Open directory for files ... 21:53:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:53:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:53:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:53:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:53:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:53:48 INFO - (blobuploader) - INFO - Done attempting. 21:53:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:53:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:53:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:53:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:53:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:53:50 INFO - (blobuploader) - INFO - Done attempting. 21:53:50 INFO - (blobuploader) - INFO - Iteration through files over. 21:53:50 INFO - Return code: 0 21:53:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:53:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:53:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae99823c46ce7cecc04e2c50d4e0cb1a79d62202a36617566aac2d3588c431b24967e8f644658f68b9b339ce4a4355824969e97cbb3b69d9dea5c171310c0164", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/491509774101247f36b7f9a24739000a66caccc5157e419c3031721955be5de9d8ba7309b5b7b11d412941cecd4c9e8f925d01e5d3bdbd2dff14298b7e5085e7"} 21:53:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:53:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:53:50 INFO - Contents: 21:53:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae99823c46ce7cecc04e2c50d4e0cb1a79d62202a36617566aac2d3588c431b24967e8f644658f68b9b339ce4a4355824969e97cbb3b69d9dea5c171310c0164", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/491509774101247f36b7f9a24739000a66caccc5157e419c3031721955be5de9d8ba7309b5b7b11d412941cecd4c9e8f925d01e5d3bdbd2dff14298b7e5085e7"} 21:53:50 INFO - Running post-run listener: copy_logs_to_upload_dir 21:53:50 INFO - Copying logs to upload dir... 21:53:50 INFO - mkdir: /builds/slave/test/build/upload/logs 21:53:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.965322 ========= master_lag: 1.38 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-15 21:53:51.551493) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-15 21:53:51.555049) ========= 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.Qq2mlRuqhJ/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.buMC1hIPsr/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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/ae99823c46ce7cecc04e2c50d4e0cb1a79d62202a36617566aac2d3588c431b24967e8f644658f68b9b339ce4a4355824969e97cbb3b69d9dea5c171310c0164", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/491509774101247f36b7f9a24739000a66caccc5157e419c3031721955be5de9d8ba7309b5b7b11d412941cecd4c9e8f925d01e5d3bdbd2dff14298b7e5085e7"} build_url:https://queue.taskcluster.net/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011141 build_url: 'https://queue.taskcluster.net/v1/task/dd3BMt3LQpy1AUb_gEPj5A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae99823c46ce7cecc04e2c50d4e0cb1a79d62202a36617566aac2d3588c431b24967e8f644658f68b9b339ce4a4355824969e97cbb3b69d9dea5c171310c0164", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/491509774101247f36b7f9a24739000a66caccc5157e419c3031721955be5de9d8ba7309b5b7b11d412941cecd4c9e8f925d01e5d3bdbd2dff14298b7e5085e7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-15 21:53:51.587311) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 21:53:51.587725) ========= 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.Qq2mlRuqhJ/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.buMC1hIPsr/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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.005125 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 21:53:51.635132) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-15 21:53:51.635419) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-15 21:53:51.635766) ========= ========= Total master_lag: 4.04 =========