builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0389 starttime: 1464887945.56 results: success (0) buildid: 20160602084653 builduid: 7bd88d6f073144e79b1e6d4d820927e4 revision: fe57228e70aa503323bf177093e2cecb438a39cc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 10:19:05.556452) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 10:19:05.556947) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 10:19:05.557228) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 10:19:05.580196) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 10:19:05.580604) ========= 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.ukuRctBszD/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.b9zizCWCz5/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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-06-02 10:19:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 170M=0s 2016-06-02 10:19:05 (170 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097349 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 10:19:05.698804) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 10:19:05.699122) ========= 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.ukuRctBszD/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.b9zizCWCz5/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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.025593 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 10:19:05.747974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-02 10:19:05.748331) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev fe57228e70aa503323bf177093e2cecb438a39cc --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 fe57228e70aa503323bf177093e2cecb438a39cc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ukuRctBszD/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.b9zizCWCz5/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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-06-02 10:19:05,833 truncating revision to first 12 chars 2016-06-02 10:19:05,833 Setting DEBUG logging. 2016-06-02 10:19:05,833 attempt 1/10 2016-06-02 10:19:05,833 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/fe57228e70aa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 10:19:07,893 unpacking tar archive at: fx-team-fe57228e70aa/testing/mozharness/ program finished with exit code 0 elapsedTime=2.335179 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-02 10:19:08.103467) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 10:19:08.103807) ========= script_repo_revision: fe57228e70aa503323bf177093e2cecb438a39cc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 10:19:08.104208) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 10:19:08.104477) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 10:19:08.121794) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-06-02 10:19:08.122414) ========= /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.ukuRctBszD/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.b9zizCWCz5/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 10:19:08 INFO - MultiFileLogger online at 20160602 10:19:08 in /builds/slave/test 10:19:08 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 10:19:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:19:08 INFO - {'append_to_log': False, 10:19:08 INFO - 'base_work_dir': '/builds/slave/test', 10:19:08 INFO - 'blob_upload_branch': 'Fx-Team', 10:19:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:19:08 INFO - 'branch': 'Fx-Team', 10:19:08 INFO - 'buildbot_json_path': 'buildprops.json', 10:19:08 INFO - 'config_files': ('talos/mac_config.py',), 10:19:08 INFO - 'default_actions': ('clobber', 10:19:08 INFO - 'read-buildbot-config', 10:19:08 INFO - 'download-and-extract', 10:19:08 INFO - 'populate-webroot', 10:19:08 INFO - 'create-virtualenv', 10:19:08 INFO - 'install', 10:19:08 INFO - 'run-tests'), 10:19:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:19:08 INFO - 'download_minidump_stackwalk': True, 10:19:08 INFO - 'download_symbols': 'ondemand', 10:19:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:19:08 INFO - 'tooltool.py': '/tools/tooltool.py', 10:19:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:19:08 INFO - '/tools/misc-python/virtualenv.py')}, 10:19:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:19:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:19:08 INFO - 'installer_path': 'installer.exe', 10:19:08 INFO - 'log_level': 'info', 10:19:08 INFO - 'log_name': 'talos', 10:19:08 INFO - 'log_to_console': True, 10:19:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:19:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:19:08 INFO - 'opt_config_files': (), 10:19:08 INFO - 'pip_index': False, 10:19:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:19:08 INFO - 'cmd': ('bash', 10:19:08 INFO - '-c', 10:19:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:19:08 INFO - 'enabled': True, 10:19:08 INFO - 'halt_on_failure': False, 10:19:08 INFO - 'name': 'check_screen_resolution'},), 10:19:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:19:08 INFO - 'cmd': ('bash', 10:19:08 INFO - '-c', 10:19:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:19:08 INFO - 'enabled': True, 10:19:08 INFO - 'halt_on_failure': False, 10:19:08 INFO - 'name': 'check_screen_resolution'},), 10:19:08 INFO - 'run_cmd_checks_enabled': True, 10:19:08 INFO - 'sps_profile': False, 10:19:08 INFO - 'sps_profile_interval': 0, 10:19:08 INFO - 'suite': 'dromaeojs', 10:19:08 INFO - 'system_bits': '32', 10:19:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:19:08 INFO - 'title': 't-yosemite-r7-0389', 10:19:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:19:08 INFO - 'use_talos_json': True, 10:19:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:19:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:19:08 INFO - 'work_dir': 'build'} 10:19:08 INFO - ##### 10:19:08 INFO - ##### Running clobber step. 10:19:08 INFO - ##### 10:19:08 INFO - Running pre-action listener: _resource_record_pre_action 10:19:08 INFO - Running main action method: clobber 10:19:08 INFO - rmtree: /builds/slave/test/build 10:19:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:19:10 INFO - Running post-action listener: _resource_record_post_action 10:19:10 INFO - ##### 10:19:10 INFO - ##### Finished clobber step (success) 10:19:10 INFO - ##### 10:19:10 INFO - ##### 10:19:10 INFO - ##### Running read-buildbot-config step. 10:19:10 INFO - ##### 10:19:10 INFO - Running pre-action listener: _resource_record_pre_action 10:19:10 INFO - Running main action method: read_buildbot_config 10:19:10 INFO - Using buildbot properties: 10:19:10 INFO - { 10:19:10 INFO - "project": "", 10:19:10 INFO - "product": "firefox", 10:19:10 INFO - "script_repo_revision": "production", 10:19:10 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 10:19:10 INFO - "repository": "", 10:19:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 10:19:10 INFO - "buildid": "20160602084653", 10:19:10 INFO - "pgo_build": "False", 10:19:10 INFO - "basedir": "/builds/slave/test", 10:19:10 INFO - "buildnumber": 46, 10:19:10 INFO - "slavename": "t-yosemite-r7-0389", 10:19:10 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:19:10 INFO - "platform": "yosemite_r7", 10:19:10 INFO - "scheduler": "tests-fx-team-macosx64-talos", 10:19:10 INFO - "branch": "fx-team", 10:19:10 INFO - "repo_path": "integration/fx-team", 10:19:10 INFO - "revision": "fe57228e70aa503323bf177093e2cecb438a39cc", 10:19:10 INFO - "stage_platform": "macosx64", 10:19:10 INFO - "builduid": "7bd88d6f073144e79b1e6d4d820927e4", 10:19:10 INFO - "slavebuilddir": "test" 10:19:10 INFO - } 10:19:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 10:19:10 INFO - Running post-action listener: _resource_record_post_action 10:19:10 INFO - ##### 10:19:10 INFO - ##### Finished read-buildbot-config step (success) 10:19:10 INFO - ##### 10:19:10 INFO - ##### 10:19:10 INFO - ##### Running download-and-extract step. 10:19:10 INFO - ##### 10:19:10 INFO - Running pre-action listener: _resource_record_pre_action 10:19:10 INFO - Running main action method: download_and_extract 10:19:10 INFO - mkdir: /builds/slave/test/build/tests 10:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:19:10 INFO - https://queue.taskcluster.net/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:19:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:19:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:19:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:19:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 10:19:12 INFO - Downloaded 1199 bytes. 10:19:12 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:19:12 INFO - Using the following test package requirements: 10:19:12 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:19:12 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:19:12 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 10:19:12 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:19:12 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 10:19:12 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:19:12 INFO - u'jsshell-mac.zip'], 10:19:12 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:19:12 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 10:19:12 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:19:12 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:19:12 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 10:19:12 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:19:12 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 10:19:12 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:19:12 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 10:19:12 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:19:12 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 10:19:12 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:19: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')]} 10:19:12 INFO - https://queue.taskcluster.net/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:19:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:19:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:19:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:19:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:19:13 INFO - Downloaded 18713571 bytes. 10:19:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:19:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:19:16 INFO - Return code: 0 10:19:16 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:19:16 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')]} 10:19:16 INFO - https://queue.taskcluster.net/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:19:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:19:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:19:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:19:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:19:17 INFO - Downloaded 18713571 bytes. 10:19:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:19:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:19:20 INFO - Return code: 0 10:19:20 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')]} 10:19:20 INFO - https://queue.taskcluster.net/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:19:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:19:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:19:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:19:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:19:22 INFO - Downloaded 10834055 bytes. 10:19:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:19:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:19:22 INFO - Return code: 0 10:19: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')]} 10:19:22 INFO - https://queue.taskcluster.net/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:19:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:19:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:19:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 10:19:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 10:19:25 INFO - Downloaded 111079536 bytes. 10:19:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:19:25 INFO - mkdir: /builds/slave/test/properties 10:19:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:19:25 INFO - Writing to file /builds/slave/test/properties/build_url 10:19:25 INFO - Contents: 10:19:25 INFO - build_url:https://queue.taskcluster.net/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:19:26 INFO - Running post-action listener: _resource_record_post_action 10:19:26 INFO - Running post-action listener: set_extra_try_arguments 10:19:26 INFO - ##### 10:19:26 INFO - ##### Finished download-and-extract step (success) 10:19:26 INFO - ##### 10:19:26 INFO - ##### 10:19:26 INFO - ##### Running populate-webroot step. 10:19:26 INFO - ##### 10:19:26 INFO - Running pre-action listener: _resource_record_pre_action 10:19:26 INFO - Running main action method: populate_webroot 10:19:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:19:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:19:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:19:26 INFO - '--tppagecycles', 10:19:26 INFO - '7'], 10:19:26 INFO - 'tests': ['tsvgm']}}, 10:19:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:19:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:19:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:19:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:19:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:19:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:19:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:26 INFO - 'talos_options': ['--disable-e10s'], 10:19:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:19:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:19:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:26 INFO - 'talos_options': ['--disable-e10s'], 10:19:26 INFO - 'tests': ['damp', 'tps']}, 10:19:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:26 INFO - 'tests': ['damp', 'tps']}, 10:19:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:19:26 INFO - 'tests': ['dromaeo_dom']}, 10:19:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:19:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:19:26 INFO - 'tests': ['a11yr', 10:19:26 INFO - 'ts_paint', 10:19:26 INFO - 'tpaint', 10:19:26 INFO - 'sessionrestore', 10:19:26 INFO - 'sessionrestore_no_auto_restore', 10:19:26 INFO - 'tabpaint']}, 10:19:26 INFO - 'other-e10s': {'tests': ['a11yr', 10:19:26 INFO - 'ts_paint', 10:19:26 INFO - 'tpaint', 10:19:26 INFO - 'sessionrestore', 10:19:26 INFO - 'sessionrestore_no_auto_restore', 10:19:26 INFO - 'tabpaint']}, 10:19:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:19:26 INFO - 'ts_paint', 10:19:26 INFO - 'tpaint', 10:19:26 INFO - 'sessionrestore', 10:19:26 INFO - 'sessionrestore_no_auto_restore', 10:19:26 INFO - 'tabpaint']}, 10:19:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:19:26 INFO - 'ts_paint', 10:19:26 INFO - 'tpaint', 10:19:26 INFO - 'sessionrestore', 10:19:26 INFO - 'sessionrestore_no_auto_restore', 10:19:26 INFO - 'tabpaint']}, 10:19:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:19:26 INFO - 'tests': ['a11yr', 10:19:26 INFO - 'ts_paint', 10:19:26 INFO - 'tpaint', 10:19:26 INFO - 'sessionrestore', 10:19:26 INFO - 'sessionrestore_no_auto_restore', 10:19:26 INFO - 'tabpaint']}, 10:19:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:19:26 INFO - 'tests': ['a11yr', 10:19:26 INFO - 'ts_paint', 10:19:26 INFO - 'tpaint', 10:19:26 INFO - 'sessionrestore', 10:19:26 INFO - 'sessionrestore_no_auto_restore', 10:19:26 INFO - 'tabpaint']}, 10:19:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:19:26 INFO - 'tests': ['tsvgx', 10:19:26 INFO - 'tsvgr_opacity', 10:19:26 INFO - 'tart', 10:19:26 INFO - 'tscrollx', 10:19:26 INFO - 'cart']}, 10:19:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:19:26 INFO - 'tsvgr_opacity', 10:19:26 INFO - 'tart', 10:19:26 INFO - 'tscrollx', 10:19:26 INFO - 'cart']}, 10:19:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:26 INFO - 'talos_options': ['--disable-e10s'], 10:19:26 INFO - 'tests': ['tp5o']}, 10:19:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:26 INFO - 'tests': ['tp5o']}, 10:19:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:26 INFO - 'talos_options': ['--disable-e10s', 10:19:26 INFO - '--xperf_path', 10:19:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:19:26 INFO - 'tests': ['tp5n']}, 10:19:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:26 INFO - 'talos_options': ['--xperf_path', 10:19:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:19:26 INFO - 'tests': ['tp5n']}}, 10:19:26 INFO - 'talos.zip': {'path': '', 10:19:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:19:26 INFO - Running post-action listener: _resource_record_post_action 10:19:26 INFO - ##### 10:19:26 INFO - ##### Finished populate-webroot step (success) 10:19:26 INFO - ##### 10:19:26 INFO - ##### 10:19:26 INFO - ##### Running create-virtualenv step. 10:19:26 INFO - ##### 10:19:26 INFO - Running pre-action listener: _resource_record_pre_action 10:19:26 INFO - Running main action method: create_virtualenv 10:19:26 INFO - Creating virtualenv /builds/slave/test/build/venv 10:19:26 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 10:19:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:19:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:19:27 INFO - Using real prefix '/tools/python27' 10:19:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:19:27 INFO - Installing distribute.............................................................................................................................................................................................done. 10:19:30 INFO - Installing pip.................done. 10:19:30 INFO - Return code: 0 10:19:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:19: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')]} 10:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19: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')]} 10:19:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1006a0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da55b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100544d30>, '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 0x100368db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10053bb20>, '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.ukuRctBszD/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.b9zizCWCz5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 10:19: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', 'pip>=1.5'] in /builds/slave/test/build 10:19: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 pip>=1.5 10:19:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ukuRctBszD/Render', 10:19:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:30 INFO - 'HOME': '/Users/cltbld', 10:19:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:30 INFO - 'LOGNAME': 'cltbld', 10:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:30 INFO - 'MOZ_NO_REMOTE': '1', 10:19:30 INFO - 'NO_EM_RESTART': '1', 10:19:30 INFO - 'PAGER': '/bin/cat', 10:19:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:30 INFO - 'PWD': '/builds/slave/test', 10:19:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:30 INFO - 'SHELL': '/bin/bash', 10:19:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b9zizCWCz5/Listeners', 10:19:30 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 10:19:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:30 INFO - 'USER': 'cltbld', 10:19:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:30 INFO - 'XPC_FLAGS': '0x0', 10:19:30 INFO - 'XPC_SERVICE_NAME': '0', 10:19:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:30 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 10:19:30 INFO - Cleaning up... 10:19:30 INFO - Return code: 0 10:19:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:19: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')]} 10:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19: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')]} 10:19:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19: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', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1006a0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da55b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100544d30>, '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 0x100368db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10053bb20>, '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.ukuRctBszD/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.b9zizCWCz5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 10:19: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', 'psutil>=3.1.1'] in /builds/slave/test/build 10:19: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 psutil>=3.1.1 10:19:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ukuRctBszD/Render', 10:19:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:30 INFO - 'HOME': '/Users/cltbld', 10:19:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:30 INFO - 'LOGNAME': 'cltbld', 10:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:30 INFO - 'MOZ_NO_REMOTE': '1', 10:19:30 INFO - 'NO_EM_RESTART': '1', 10:19:30 INFO - 'PAGER': '/bin/cat', 10:19:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:30 INFO - 'PWD': '/builds/slave/test', 10:19:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:30 INFO - 'SHELL': '/bin/bash', 10:19:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b9zizCWCz5/Listeners', 10:19:30 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 10:19:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:30 INFO - 'USER': 'cltbld', 10:19:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:30 INFO - 'XPC_FLAGS': '0x0', 10:19:30 INFO - 'XPC_SERVICE_NAME': '0', 10:19:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:31 INFO - Downloading/unpacking psutil>=3.1.1 10:19: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 10:19: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 10:19: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 10:19: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 10:19: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 10:19: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 10:19:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:19:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:19:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:19:34 INFO - Installing collected packages: psutil 10:19:34 INFO - Running setup.py install for psutil 10:19:34 INFO - building 'psutil._psutil_osx' extension 10:19:34 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 10:19:34 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 10:19:34 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 10:19:34 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 10:19:35 INFO - building 'psutil._psutil_posix' extension 10:19:35 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 10:19:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:19:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:19:35 INFO - ^ 10:19:35 INFO - 1 warning generated. 10:19:35 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 10:19:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:19:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:19:35 INFO - Successfully installed psutil 10:19:35 INFO - Cleaning up... 10:19:35 INFO - Return code: 0 10:19:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:19:35 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')]} 10:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:35 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')]} 10:19:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1006a0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da55b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100544d30>, '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 0x100368db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10053bb20>, '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.ukuRctBszD/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.b9zizCWCz5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 10:19:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:19:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:19:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ukuRctBszD/Render', 10:19:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:35 INFO - 'HOME': '/Users/cltbld', 10:19:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:35 INFO - 'LOGNAME': 'cltbld', 10:19:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:35 INFO - 'MOZ_NO_REMOTE': '1', 10:19:35 INFO - 'NO_EM_RESTART': '1', 10:19:35 INFO - 'PAGER': '/bin/cat', 10:19:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:35 INFO - 'PWD': '/builds/slave/test', 10:19:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:35 INFO - 'SHELL': '/bin/bash', 10:19:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b9zizCWCz5/Listeners', 10:19:35 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 10:19:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:35 INFO - 'USER': 'cltbld', 10:19:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:35 INFO - 'XPC_FLAGS': '0x0', 10:19:35 INFO - 'XPC_SERVICE_NAME': '0', 10:19:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:19:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:19:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:19:38 INFO - Installing collected packages: mozsystemmonitor 10:19:38 INFO - Running setup.py install for mozsystemmonitor 10:19:38 INFO - Successfully installed mozsystemmonitor 10:19:38 INFO - Cleaning up... 10:19:38 INFO - Return code: 0 10:19:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:19:38 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')]} 10:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:38 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')]} 10:19:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:38 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 0x1006a0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da55b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100544d30>, '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 0x100368db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10053bb20>, '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.ukuRctBszD/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.b9zizCWCz5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 10:19:38 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 10:19:38 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 10:19:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ukuRctBszD/Render', 10:19:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:38 INFO - 'HOME': '/Users/cltbld', 10:19:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:38 INFO - 'LOGNAME': 'cltbld', 10:19:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:38 INFO - 'MOZ_NO_REMOTE': '1', 10:19:38 INFO - 'NO_EM_RESTART': '1', 10:19:38 INFO - 'PAGER': '/bin/cat', 10:19:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:38 INFO - 'PWD': '/builds/slave/test', 10:19:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:38 INFO - 'SHELL': '/bin/bash', 10:19:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b9zizCWCz5/Listeners', 10:19:38 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 10:19:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:38 INFO - 'USER': 'cltbld', 10:19:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:38 INFO - 'XPC_FLAGS': '0x0', 10:19:38 INFO - 'XPC_SERVICE_NAME': '0', 10:19:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:38 INFO - Downloading/unpacking blobuploader==1.2.4 10:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:41 INFO - Downloading blobuploader-1.2.4.tar.gz 10:19:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:19:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:19:41 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 10:19:41 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 10:19:41 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 10:19:41 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 10:19:41 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 10:19:41 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 10:19:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:19:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:19:41 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 10:19:41 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 10:19:41 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 10:19:41 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 10:19:41 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 10:19:41 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 10:19:42 INFO - Downloading docopt-0.6.1.tar.gz 10:19:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:19:42 INFO - Installing collected packages: blobuploader, requests, docopt 10:19:42 INFO - Running setup.py install for blobuploader 10:19:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:19:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:19:42 INFO - Running setup.py install for requests 10:19:43 INFO - Running setup.py install for docopt 10:19:43 INFO - Successfully installed blobuploader requests docopt 10:19:43 INFO - Cleaning up... 10:19:43 INFO - Return code: 0 10:19:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:19:43 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')]} 10:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:43 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')]} 10:19:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:43 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 0x1006a0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da55b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100544d30>, '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 0x100368db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10053bb20>, '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.ukuRctBszD/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.b9zizCWCz5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 10:19:43 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 10:19:43 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 10:19:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ukuRctBszD/Render', 10:19:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:43 INFO - 'HOME': '/Users/cltbld', 10:19:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:43 INFO - 'LOGNAME': 'cltbld', 10:19:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:43 INFO - 'MOZ_NO_REMOTE': '1', 10:19:43 INFO - 'NO_EM_RESTART': '1', 10:19:43 INFO - 'PAGER': '/bin/cat', 10:19:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:43 INFO - 'PWD': '/builds/slave/test', 10:19:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:43 INFO - 'SHELL': '/bin/bash', 10:19:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b9zizCWCz5/Listeners', 10:19:43 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 10:19:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:43 INFO - 'USER': 'cltbld', 10:19:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:43 INFO - 'XPC_FLAGS': '0x0', 10:19:43 INFO - 'XPC_SERVICE_NAME': '0', 10:19:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:19:43 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-4RC5I0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:19:43 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-WDZ1Ui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:19:43 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-wxNni_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:19:43 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-FEonvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:19:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-UxrX1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:19:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-UpI4pV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:19:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-0IhaBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:19:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-ZybeDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:19:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-bOlsnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:19:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-kCTQAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:19:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-pyKb53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:19:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-hHu5je-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:19:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-GeSiRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:19:44 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-rCWvPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:19:45 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-SUg_rv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:19:45 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-yUM3aH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:19:45 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-GEzLob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:19:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:19:45 INFO - Running setup.py install for manifestparser 10:19:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:19:45 INFO - Running setup.py install for mozcrash 10:19:45 INFO - Running setup.py install for mozdebug 10:19:45 INFO - Running setup.py install for mozdevice 10:19:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:19:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:19:45 INFO - Running setup.py install for mozfile 10:19:46 INFO - Running setup.py install for mozhttpd 10:19:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:19:46 INFO - Running setup.py install for mozinfo 10:19:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:19:46 INFO - Running setup.py install for mozInstall 10:19:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:19:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:19:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:19:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:19:46 INFO - Running setup.py install for mozleak 10:19:46 INFO - Running setup.py install for mozlog 10:19:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:19:46 INFO - Running setup.py install for moznetwork 10:19:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:19:46 INFO - Running setup.py install for mozprocess 10:19:47 INFO - Running setup.py install for mozprofile 10:19:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:19:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:19:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:19:47 INFO - Running setup.py install for mozrunner 10:19:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:19:47 INFO - Running setup.py install for mozscreenshot 10:19:47 INFO - Running setup.py install for moztest 10:19:47 INFO - Running setup.py install for mozversion 10:19:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:19:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:19:47 INFO - Cleaning up... 10:19:47 INFO - Return code: 0 10:19:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:19:47 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')]} 10:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:47 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')]} 10:19:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:47 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 0x1006a0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da55b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100544d30>, '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 0x100368db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10053bb20>, '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.ukuRctBszD/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.b9zizCWCz5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 10:19:47 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 10:19:47 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 10:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ukuRctBszD/Render', 10:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:47 INFO - 'HOME': '/Users/cltbld', 10:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:47 INFO - 'LOGNAME': 'cltbld', 10:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:47 INFO - 'MOZ_NO_REMOTE': '1', 10:19:47 INFO - 'NO_EM_RESTART': '1', 10:19:47 INFO - 'PAGER': '/bin/cat', 10:19:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:47 INFO - 'PWD': '/builds/slave/test', 10:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:47 INFO - 'SHELL': '/bin/bash', 10:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b9zizCWCz5/Listeners', 10:19:47 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 10:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:47 INFO - 'USER': 'cltbld', 10:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:47 INFO - 'XPC_FLAGS': '0x0', 10:19:47 INFO - 'XPC_SERVICE_NAME': '0', 10:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:19:48 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-V3jKQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:19:48 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)) 10:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:19:48 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-9NTxZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:19:48 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)) 10:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:19:48 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-jTed42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:19:48 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)) 10:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:19:48 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-X5vS5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:19:48 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)) 10:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:19:48 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-e11knR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:19:48 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)) 10:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:19:48 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-UpdOnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:19:48 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)) 10:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:19:48 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-eJATR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:19:48 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)) 10:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:19:48 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-uTzrH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:19:49 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)) 10:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:19:49 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-iCdFG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:19:49 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)) 10:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:19:49 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-Hp42pj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:19:49 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-18ylih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:19:49 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)) 10:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:19:49 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-t9gbAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:19:49 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)) 10:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:19:49 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-CFtDUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:19:49 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)) 10:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:19:49 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-XkClw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:19:49 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)) 10:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:19:49 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-45sU4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:19:49 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)) 10:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:19:49 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-uuyPnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:19:49 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)) 10:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:19:49 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-hFLTOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:19:49 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)) 10:19:49 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)) 10:19:49 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)) 10:19:49 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)) 10:19:49 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)) 10:19:49 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)) 10:19:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:19:49 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 10:19:49 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 10:19:49 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 10:19:49 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 10:19:49 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 10:19:49 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 10:19:52 INFO - Downloading blessings-1.6.tar.gz 10:19:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:19:52 INFO - Installing collected packages: blessings 10:19:52 INFO - Running setup.py install for blessings 10:19:52 INFO - Successfully installed blessings 10:19:52 INFO - Cleaning up... 10:19:52 INFO - Return code: 0 10:19:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:19:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:19:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:19:53 INFO - Reading from file tmpfile_stdout 10:19:53 INFO - Current package versions: 10:19:53 INFO - blessings == 1.6 10:19:53 INFO - blobuploader == 1.2.4 10:19:53 INFO - docopt == 0.6.1 10:19:53 INFO - manifestparser == 1.1 10:19:53 INFO - mozInstall == 1.12 10:19:53 INFO - mozcrash == 0.17 10:19:53 INFO - mozdebug == 0.1 10:19:53 INFO - mozdevice == 0.48 10:19:53 INFO - mozfile == 1.2 10:19:53 INFO - mozhttpd == 0.7 10:19:53 INFO - mozinfo == 0.9 10:19:53 INFO - mozleak == 0.1 10:19:53 INFO - mozlog == 3.2 10:19:53 INFO - moznetwork == 0.27 10:19:53 INFO - mozprocess == 0.22 10:19:53 INFO - mozprofile == 0.28 10:19:53 INFO - mozrunner == 6.11 10:19:53 INFO - mozscreenshot == 0.1 10:19:53 INFO - mozsystemmonitor == 0.3 10:19:53 INFO - moztest == 0.7 10:19:53 INFO - mozversion == 1.4 10:19:53 INFO - psutil == 3.1.1 10:19:53 INFO - requests == 1.2.3 10:19:53 INFO - wsgiref == 0.1.2 10:19:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:19:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:19:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:53 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 0x1006a0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da55b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100544d30>, '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 0x100368db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10053bb20>, '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.ukuRctBszD/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.b9zizCWCz5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 10:19:53 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 10:19:53 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 10:19:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ukuRctBszD/Render', 10:19:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:53 INFO - 'HOME': '/Users/cltbld', 10:19:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:53 INFO - 'LOGNAME': 'cltbld', 10:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:53 INFO - 'MOZ_NO_REMOTE': '1', 10:19:53 INFO - 'NO_EM_RESTART': '1', 10:19:53 INFO - 'PAGER': '/bin/cat', 10:19:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:53 INFO - 'PWD': '/builds/slave/test', 10:19:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:53 INFO - 'SHELL': '/bin/bash', 10:19:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b9zizCWCz5/Listeners', 10:19:53 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 10:19:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:53 INFO - 'USER': 'cltbld', 10:19:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:53 INFO - 'XPC_FLAGS': '0x0', 10:19:53 INFO - 'XPC_SERVICE_NAME': '0', 10:19:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:53 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)) 10:19:53 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)) 10:19:53 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)) 10:19:53 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)) 10:19:53 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)) 10:19:53 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)) 10:19:53 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)) 10:19:53 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)) 10:19:53 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)) 10:19:53 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)) 10:19:53 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)) 10:19:53 INFO - Cleaning up... 10:19:53 INFO - Return code: 0 10:19:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:19:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:19:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1006a0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da55b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100544d30>, '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 0x100368db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10053bb20>, '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.ukuRctBszD/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.b9zizCWCz5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 10:19:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:19:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:19:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ukuRctBszD/Render', 10:19:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:53 INFO - 'HOME': '/Users/cltbld', 10:19:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:53 INFO - 'LOGNAME': 'cltbld', 10:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:53 INFO - 'MOZ_NO_REMOTE': '1', 10:19:53 INFO - 'NO_EM_RESTART': '1', 10:19:53 INFO - 'PAGER': '/bin/cat', 10:19:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:53 INFO - 'PWD': '/builds/slave/test', 10:19:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:53 INFO - 'SHELL': '/bin/bash', 10:19:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b9zizCWCz5/Listeners', 10:19:53 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 10:19:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:53 INFO - 'USER': 'cltbld', 10:19:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:53 INFO - 'XPC_FLAGS': '0x0', 10:19:53 INFO - 'XPC_SERVICE_NAME': '0', 10:19:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:53 INFO - Downloading/unpacking jsonschema 10:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:19:56 INFO - Downloading/unpacking functools32 (from jsonschema) 10:19:56 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 10:19:56 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 10:19:56 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 10:19:56 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 10:19:56 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 10:19:56 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 10:19:56 INFO - Downloading functools32-3.2.3-2.tar.gz 10:19:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:19:56 INFO - warning: no files found matching '*.txt' 10:19:56 INFO - no previously-included directories found matching 'build' 10:19:56 INFO - no previously-included directories found matching 'dist' 10:19:56 INFO - no previously-included directories found matching '.git*' 10:19:56 INFO - Installing collected packages: jsonschema, functools32 10:19:56 INFO - Running setup.py install for functools32 10:19:56 INFO - warning: no files found matching '*.txt' 10:19:56 INFO - no previously-included directories found matching 'build' 10:19:56 INFO - no previously-included directories found matching 'dist' 10:19:56 INFO - no previously-included directories found matching '.git*' 10:19:56 INFO - Successfully installed jsonschema functools32 10:19:56 INFO - Cleaning up... 10:19:56 INFO - Return code: 0 10:19:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:19: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')]} 10:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19: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')]} 10:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:56 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 0x1006a0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da55b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100544d30>, '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 0x100368db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10053bb20>, '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.ukuRctBszD/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.b9zizCWCz5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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 10:19:56 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 10:19:56 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 10:19:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ukuRctBszD/Render', 10:19:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:56 INFO - 'HOME': '/Users/cltbld', 10:19:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:56 INFO - 'LOGNAME': 'cltbld', 10:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:56 INFO - 'MOZ_NO_REMOTE': '1', 10:19:56 INFO - 'NO_EM_RESTART': '1', 10:19:56 INFO - 'PAGER': '/bin/cat', 10:19:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:56 INFO - 'PWD': '/builds/slave/test', 10:19:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:56 INFO - 'SHELL': '/bin/bash', 10:19:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b9zizCWCz5/Listeners', 10:19:56 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 10:19:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:56 INFO - 'USER': 'cltbld', 10:19:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:56 INFO - 'XPC_FLAGS': '0x0', 10:19:56 INFO - 'XPC_SERVICE_NAME': '0', 10:19:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:57 INFO - Downloading/unpacking flake8 10:19:57 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 10:19:57 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 10:19:57 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 10:19:57 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 10:19:57 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 10:19:57 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 10:19:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:19:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:19:59 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 10:19:59 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 10:19:59 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 10:19:59 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 10:19:59 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 10:19:59 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 10:20:00 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:20:00 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:20:00 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 10:20:00 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 10:20:00 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 10:20:00 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 10:20:00 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 10:20:00 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 10:20:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:20:00 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 10:20:00 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 10:20:00 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 10:20:00 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 10:20:00 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 10:20:00 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 10:20:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:20:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:20:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:20:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:20:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:20:00 INFO - no previously-included directories found matching 'docs/_build' 10:20:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:20:01 INFO - Running setup.py install for pep8 10:20:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:20:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:20:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:20:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:20:01 INFO - no previously-included directories found matching 'docs/_build' 10:20:01 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:20:01 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:20:01 INFO - Cleaning up... 10:20:01 INFO - Return code: 0 10:20:01 INFO - Running post-action listener: _resource_record_post_action 10:20:01 INFO - Running post-action listener: _start_resource_monitoring 10:20:01 INFO - Starting resource monitoring. 10:20:01 INFO - ##### 10:20:01 INFO - ##### Finished create-virtualenv step (success) 10:20:01 INFO - ##### 10:20:01 INFO - ##### 10:20:01 INFO - ##### Running install step. 10:20:01 INFO - ##### 10:20:01 INFO - Running pre-action listener: _resource_record_pre_action 10:20:01 INFO - Running main action method: install 10:20:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:20:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:20:01 INFO - Reading from file tmpfile_stdout 10:20:01 INFO - Detecting whether we're running mozinstall >=1.0... 10:20:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:20:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:20:01 INFO - Reading from file tmpfile_stdout 10:20:01 INFO - Output received: 10:20:01 INFO - Usage: mozinstall [options] installer 10:20:01 INFO - Options: 10:20:01 INFO - -h, --help show this help message and exit 10:20:01 INFO - -d DEST, --destination=DEST 10:20:01 INFO - Directory to install application into. [default: 10:20:01 INFO - "/builds/slave/test"] 10:20:01 INFO - --app=APP Application being installed. [default: firefox] 10:20:01 INFO - mkdir: /builds/slave/test/build/application 10:20:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:20:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:20:35 INFO - Reading from file tmpfile_stdout 10:20:35 INFO - Output received: 10:20:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:20:35 INFO - Running post-action listener: _resource_record_post_action 10:20:35 INFO - ##### 10:20:35 INFO - ##### Finished install step (success) 10:20:35 INFO - ##### 10:20:35 INFO - ##### 10:20:35 INFO - ##### Running run-tests step. 10:20:35 INFO - ##### 10:20:35 INFO - Running pre-action listener: _resource_record_pre_action 10:20:35 INFO - Running main action method: run_tests 10:20:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:20:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:20:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:20:35 INFO - 2016-06-02 10:20:35.700 screenresolution[1641:7484] starting screenresolution argv=screenresolution get 10:20:35 INFO - 2016-06-02 10:20:35.703 screenresolution[1641:7484] Display 0: 1600x1200x32@60 10:20:35 INFO - 2016-06-02 10:20:35.709 screenresolution[1642:7487] starting screenresolution argv=screenresolution list 10:20:35 INFO - Available Modes on Display 0 10:20:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:20:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:20:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:20:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:20:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:20:35 INFO - Intel Iris: 10:20:35 INFO - Chipset Model: Intel Iris 10:20:35 INFO - Type: GPU 10:20:35 INFO - Bus: Built-In 10:20:35 INFO - VRAM (Dynamic, Max): 1536 MB 10:20:35 INFO - Vendor: Intel (0x8086) 10:20:35 INFO - Device ID: 0x0a2e 10:20:35 INFO - Revision ID: 0x0009 10:20:35 INFO - Displays: 10:20:35 INFO - Display: 10:20:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:20:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:20:35 INFO - Display Serial Number: 1600x1200 60 10:20:35 INFO - Main Display: Yes 10:20:35 INFO - Mirror: Off 10:20:35 INFO - Online: Yes 10:20:35 INFO - Rotation: Supported 10:20:35 INFO - Return code: 0 10:20:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:20:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:20:35 INFO - Python 2.7.3 10:20:35 INFO - Return code: 0 10:20:35 INFO - grabbing minidump binary from tooltool 10:20:35 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')]} 10:20:35 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100544d30>, '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 0x100368db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10053bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:20:35 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 10:20:35 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 10:20:35 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:20:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:20:36 INFO - Return code: 0 10:20:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:20:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:20:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:20:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:20:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:20:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:20:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:20:37 INFO - Return code: 0 10:20:37 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/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0389', '--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 10:20:37 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/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0389 --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 10:20:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ukuRctBszD/Render', 10:20:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:37 INFO - 'HOME': '/Users/cltbld', 10:20:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:37 INFO - 'LOGNAME': 'cltbld', 10:20:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:20:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:20:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:37 INFO - 'MOZ_NO_REMOTE': '1', 10:20:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:20:37 INFO - 'NO_EM_RESTART': '1', 10:20:37 INFO - 'PAGER': '/bin/cat', 10:20:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:37 INFO - 'PWD': '/builds/slave/test', 10:20:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:20:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:37 INFO - 'SHELL': '/bin/bash', 10:20:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b9zizCWCz5/Listeners', 10:20:37 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 10:20:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:37 INFO - 'USER': 'cltbld', 10:20:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:37 INFO - 'XPC_FLAGS': '0x0', 10:20:37 INFO - 'XPC_SERVICE_NAME': '0', 10:20:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:37 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/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0389', '--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 10:20:37 INFO - mozversion application_buildid: 20160602084653 10:20:37 INFO - mozversion application_changeset: fe57228e70aa503323bf177093e2cecb438a39cc 10:20:37 INFO - mozversion application_display_name: Nightly 10:20:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:20:37 INFO - mozversion application_name: Firefox 10:20:37 INFO - mozversion application_remotingname: firefox 10:20:37 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:20:37 INFO - mozversion application_vendor: Mozilla 10:20:37 INFO - mozversion application_version: 49.0a1 10:20:37 INFO - mozversion platform_buildid: 20160602084653 10:20:37 INFO - mozversion platform_changeset: fe57228e70aa503323bf177093e2cecb438a39cc 10:20:37 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:20:37 INFO - mozversion platform_version: 49.0a1 10:20:37 INFO - using testdate: 1464888037 10:20:37 INFO - actual date: 1464888037 10:20:37 INFO - starting webserver on 'localhost:49275' 10:20:37 INFO - SUITE-START | Running 2 tests 10:20:37 INFO - TEST-START | dromaeo_css 10:20:37 INFO - operating with platform_type : mac_ 10:20:37 INFO - Initialising browser for dromaeo_css test... 10:20:37 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/tmpZLGcRm/profile http://localhost:49275/getInfo.html) 10:20:39 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 10:20:39 INFO - PROCESS | 1660 | colorDepth:24 10:20:39 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 10:20:39 INFO - PROCESS | 1660 | __metrics 10:20:39 INFO - PROCESS | 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:20:39 ERROR - PROCESS | 1660 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:20:40 INFO - TEST-INFO | 1660: exit 0 10:20:40 INFO - Browser initialized. 10:20:40 INFO - Running cycle 1/1 for dromaeo_css test... 10:20:40 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/tmpZLGcRm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:20:52 INFO - PROCESS | 1662 | RSS: Main: 242327552 10:20:52 INFO - PROCESS | 1662 | 10:21:43 INFO - PROCESS | 1662 | 1464888103491 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] 10:23:54 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 10:23:56 INFO - PROCESS | 1662 | RSS: Main: 380792832 10:23:56 INFO - PROCESS | 1662 | 10:26:59 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 10:26:59 INFO - PROCESS | 1662 | RSS: Main: 376745984 10:26:59 INFO - PROCESS | 1662 | 10:30:02 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 10:30:02 INFO - PROCESS | 1662 | RSS: Main: 370950144 10:30:02 INFO - PROCESS | 1662 | 10:33:05 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 10:33:05 INFO - PROCESS | 1662 | RSS: Main: 371871744 10:33:05 INFO - PROCESS | 1662 | 10:36:09 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 10:36:09 INFO - PROCESS | 1662 | RSS: Main: 337801216 10:36:09 INFO - PROCESS | 1662 | 10:39:12 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 10:39:12 INFO - PROCESS | 1662 | RSS: Main: 376848384 10:39:12 INFO - PROCESS | 1662 | 10:39:12 INFO - PROCESS | 1662 | __start_tp_report 10:39:12 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 10:39:12 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 10:39:12 INFO - PROCESS | 1662 | |i|pagename|runs| 10:39:12 INFO - PROCESS | 1662 | |0;dojo.html;3517.7361697872657;3522.1370764162784;3531.9646803531964;3541.3271478419083;3606.5311509503777;5658.264425624663;5746.511546518545;5777.42225777422;5798.56510761693;5845.7369418376165;5751.56863235258;5786.1610066540225;5843.824685259438;5868.706564671771;5904.350521442638;4733.242681171013;4781.713097214179;5154;5238.4499627539135;5340.759665815048;5333.38666053403;5367.892642147155;5372.57019438444;5404.378496472917;5429.972850135744;5176.352955880515;5219.112750832368;5230.189320655267;5269.1832765921345;5293.232481290192;5283.075461794209;5293.311869456946;5295.258663787073;5303.204519322094;5308;4283.764392958386;5174.24973378858;5225;5229.607779416559;5251.002309561171;4701.106789709944;5661.801836935688;5738.885222295531;5768.250127483442;5769.711514424304;6274.247090349141;6287.276963149251;6331.905021424683;6332.968335158294;6344.968275158641;5232.372115346145;6161.784337548209;6241.906371404432;6351;6374.043893416025;4843.757812109416;5825.621334613236;5905.881882362329;5940.79207227745;5945.10823376497;6340.714667839959;6345.7144428499905;6363.427291543783;6364.522660800459;6372.745090196433;6052.213212282463;6103.877922441615;6139.2325959255095;6200.844978875564;6219.098230756515;6267.811965641038;6343.207099112611;6345.07996340528;6348.428641422294;6355.3644635537;6237.376262373727;6262.091996660459;6272.717727702265;6275;6287.528435367366;5236.214567814858;5988.880222395528;6086.02623580225;6172.105044768484;6187;4975.0796102721115;4979.545635078194;4997.350344455271;5008.424031236382;5047.4447810740785;4472.239719247736;4479.171353299585;4480.238359478896;4511.142882852248;4539.114872599877;5638.605297629127;5691.857703557362;5699.2590963174525;5772.855678607984;5785.27684039495;6314.084457753692;6350.4602108820345;6358.459530939829;6361.077643741632;6363.045543168561;5783.739731712083;5786.681732504669;5787.131930210502;5799.768009279666;5843.941560584425;5355.169948657965;5365.302510673588;5432.864178395572;5474.808381706621;5483;6370.713317900706;6431.260405053479;6468.741250350122;6469.870602587827;6484.837879053061;6320.683965801783;6339.144215531029;6361.904571431525;6369.14016607752;6385.808425747235;5594;6268.686565671789;6328.303886572381;6399.520035997226;6457.160569125984;6261.592996455216;6292.402221788922;6299.055141728777;6300;6300.84247893788;6285.96857015712;6293.465055470337;6331.683415829282;6344.619322840645;6345.841353966188;5986.952283350309;6041.697915104315;6114.449699527064;6214;6216.160818289654;6104.701693046632;6188.164597779242;6208.2239720034995;6214.564980451325;6223.128761973237;6042.3655516170165;6043.516518678407;6061.449321453728;6069.605475644245;6119.632822030692;5937.584369094295;6040.033594624839;6207.782727604512;6215.782447614311;6224.813255602339;5959.22530071088;6014.097885317237;6015.368386319374;6033.698315084141;6053.334133245427 10:39:12 INFO - PROCESS | 1662 | |1;ext.html;489.76491284183567;493.75312343828085;500.34454864127986;507.32272416324207;527.8469243919262;31073.37853242934;42376.15247695048;42836.357454638164;43007.78496107523;45774.31338529917;38183.61816381842;43441.56558434415;43977;44432;44475;8639.265662418702;9398.577064032135;9625.22998160149;9632.518374081303;9638.61445542181;11432.314061156316;11752.706182345424;11762.529498820037;12041.939790301036;12060.758784824298;11604.129690273221;11720.296782193096;11738.41307934604;11740.236884602473;11749.471273792657;8572.571371431466;8783.077776833405;8783.34124940632;11689.941550292237;11846.8222976655;8731.738047858575;11315.83026254607;11707;11709.355985420798;11960.760784784343;33594;41947;41953.58046419527;42021.57978420207;42068.57931420677;2989.491786396318;3481.408160612702;3484.303139372136;3531.6644918732936;3643.9453408198897;32397.838010810032;32406.837965810257;32447.78862152032;32598.348033039445;33433.49849752256;2847.8323887206147;3236.514522821572;3239.1092449576317;3243.9586246013473;3339.5825521809775;512285;513642;513776;513822;514172;60928.695356522934;60972;61486.38513614896;61520.384796152364;61751.69124154351;34347.656523434445;34384.31231375359;35516.28967420637;36992;65020.67489662521;16844;16902.323907043825;17030.233639486254;17037;17072.914635426743;11762;11847.703807404883;12905.225686458654;12990.090693651353;13246.536371226961;19851.20595176206;20113;20154.093065812074;20156.496087597927;20184.899075504527;6768.984652302096;7483;7784.571848548271;7837.531177679013;7886.408519361071;881.453498830729;991.3209451525728;1038.693585392311;1044.5560636729513;1081.4971038467152;29968;30535.847320763252;30765.230869228002;30829.307887234558;31147.532787008215;953.4946478366477;1049.994750026245;1072.6889202131451;1097.5719469406938;1174.307158776309;15072.472463463726;15314.157721325435;15354.385824567116;15716.371345146066;15785.131817749905;697572;703824;703884;705526;706637;60032.699836500535;60545.09182362268;60625;60688;60710;1279.8912092472244;1396.8812650924785;1408.1269612840106;1412.187991904659;1451.8765904898203;28031.299217519725;30926.917557885365;31013.8449307752;31284.687153128176;31462.52806207863;51652.483475164765;52459;52488.73755631197;52575;52615;545092;596204;597772.0111399415;597887;598801.0059949846;23439.648405273594;25302.240932772045;25974.74025259799;26197.476050479265;26429.867850660623;40563.59436405598;47219.527804722886;47756;48361.758191210225;48918.51081489282;36703;38317.80841095888;38419.16359237335;38876.362927375936;39096.41355379615;37862.62137378591;38336.616633833306;38452.038699032746;38862.80568597139;39343.60656393514;37241.44137837989;37905.62094379021;38043.2445944257;38275.42586861255;38297.425538616386;32724.018279451655;33218.833905831285;33470;33694.31528423618;34368;21802.672959905296;25193.496130077663;25213.24360269195;25613.743862561136;25919.48161036731 10:39:12 INFO - PROCESS | 1662 | |2;jquery.html;361.29727679663057;379.3721391097734;383.59913889984944;396.49446955132186;398.6651212981095;15483.14842683652;16011.599710007256;19783.99999999998;22223.111075556997;22234.777652223474;20150.69773953392;21010.89494552525;21372.039572299374;21575.67636485454;21694.566108677856;5735.594779279082;5801.680907550083;6038.849028774293;6041.063635136561;6066;4150.792460376984;4384.627306678936;5925.970370148143;6131.63210207389;6497.285298617148;6337.968310158442;6394.13679153313;6440.484761219114;6445.613263204223;6446.709898054575;6289.433950944437;6432;6435.646039467827;6436.420722135007;6451.93548064518;5425.213344065089;5601.495865372136;5658.773649054033;5674.460926212003;5695.373508914016;23901.163459279014;25042.248732538053;25333.61999570008;26995.865020674966;27151.321216969536;2123.522207503315;2261.5476904618977;2529.3170843872235;2644.576867701159;2859.356644754934;9968.352057116264;10024.699259022242;10042.497875106215;10366.326188797704;10372.066514013775;1862.8323450889486;1915.837153841925;1939.0983192815409;1939.6217737541185;1949.3079956615481;97978;119435.61128777376;125136;125777;126709.36645316807;9258.444493330288;9980.351277167107;11252.762196158415;11548.711282217844;11684.532618695326;14927.477538286108;14939.477118300807;14952.177630230224;14953.700925981637;14992.70014599702;7256.673449694671;8379.245867871921;8410.32717382608;8580.14198580137;8662.090480499457;8416.4950102994;8418.663253469915;8504.894363732676;8576.1423857615;8685.261752750961;4583.702059366131;4617.792199351037;4624.098300831373;4628.421447319085;4690.788914498856;1573.4571572807363;1607.541850572581;1725.9223334949957;1772.5059716341261;1916.559191385989;923.6259314977444;943.1747221181466;946.7396465971775;964.6720115160739;982.0866594067443;20320.390388288375;21452.570948580942;21507.354779356643;21627.2430464933;21880.343589692333;887.7691800132012;958.7411398922252;981.254246772458;1009.9141572966233;1019.2814066083394;8995.865062024075;10730.2488825783;11333.773324533464;11351.602693905674;12250.877491224974;560937;621147;627202;635926;636878;135750;142092;143050;143190;143427;982.4007355513131;1007.2395341517254;1030.7423144213947;1032.695354870315;1033.395463653756;17690.5577360567;18595.44213673592;18896;18998;19080.618387631894;48094.27858582194;48625;49778.751106244235;50460;50897.23654145265;53991.73004134954;71172.64413677898;71650.64174679093;73835.63082184555;74826.25173748193;7973.760787176394;8163.224493673091;8197.221263980151;8266.504009759434;8441;9110.590023449093;12575.748485030432;12906.41921113571;13457.461701531829;13513.256770877697;8919.42023768453;11510.251833630788;11703.473343699725;11713.887180717818;11967.162298639012;11471.369074700971;11508.48211830453;11514.712132196428;11690.357030363071;11828.053755699351;11622.418879055844;11629.069674426195;11857.881421185677;11967.820482692592;11986.700332491757;8241.711540096068;8437.493750374997;8446.155384461505;8507.064222935349;8557.35819813504;11268.267562608404;11420.257683250562;11573.768524629291;11630.767384652429;11729.472173752372 10:39:12 INFO - PROCESS | 1662 | |3;mootools.html;1333.6332508560138;1535.255401130452;1546.1341648676741;1782.2158250369837;1946.2215113954408;7882.724104656339;8260.132686067951;8268.049174344953;8269.25576698097;8272.50364978101;7330.486865919388;8426.789330266747;8684.218420342168;8699.347548933823;8711.43375680582;2341.4029422497238;2415.9637605435933;2416.7824895759377;2418.613021916494;2420.407000284924;2361.456864921069;2386.4607328273255;2394.281715485356;2398.7481314461993;2400.411899084727;2359.8112151027876;2373.9169129080487;2382.857028578282;2394.5091256292503;2396.9640455393182;2336.6378211377178;2342.5900467418305;2375.10933399975;2375.738668746445;2380.0126894043497;2370.454795397051;2376.7742064503846;2403.447207142367;2405.4226985523346;2406.8796560172095;8646.135386461367;8696.652133914698;8729.388942774009;8735.301175905912;8736.8689469658;5150.356205474316;5231.686098834044;5323.254744335796;5335.093034184198;5421.864453388657;3536.4341705327283;3646.6171052039685;3698.1863989922176;3722.404415293567;3736.6076561960876;1042.7591166511827;1062.0866055192528;1073.3988966178965;1084.0893649334519;1085.582050910405;11576.42117894102;11683.357415342238;11683.824742628867;11688.357140357277;11701;4319.568043195718;4513.729176249435;4613.723176609415;4646.4191976003;4652.790624421909;9159.587818548182;9160.22138118254;9164.8625270621;9167.404118732262;9176.357654964222;5239.345081864767;5245.7114858683135;5247.212918062245;5251.081060814342;5253.054450199001;4449.532799056117;4595.609373203315;4851.248056551241;4923.90152196954;4924.852254432372;3248.82131482766;3550.431930891045;3558;3760.0787806987464;3778.7543809652284;1142.9142814288962;1161.512164890748;1164.5225457562528;1168.4041139019162;1170.373849990251;601.7713268957768;812.9349652027942;865.5455885659953;964.8986856380119;969.9718298603503;5608.1587761835235;5712.285964254468;6005.279366476051;6021.879562408727;6042.969785151046;463.0622988448419;469.61960811742597;470.5929371094048;487.32018833726755;626.9467294944537;3326.600807903067;3875.5155605549307;3980.3233450313514;4079.551249362568;4082.5713300103657;10355.792884142274;10380.117689996285;10729.946350268356;10756.623518176983;10761.56953721858;10518.211134164969;10711.625093121704;10725.624603138853;10738.677839664822;10771.192160587987;7879.527228366316;8312.044114926739;8315.95842020786;8319.168083191633;8332.416730828783;4635.466921304093;5816.621919575214;5871.089981052944;6016.969915150571;6093.542984276109;9559.665411710557;9785.853212201964;9799.461029643438;10024;10032.749181270234;9793.216542676711;10141.695749127539;10393.9480302598;10396.532156052845;10396.792064158826;6937.410320122846;7124.501284910007;7143.714251429885;7345.155307139534;7673.155952845294;7899.881501777363;7982.441229113906;8116.513009219466;8286.964129483815;8292.502449853027;6508.967455162694;7307.926920730724;7504.62476876165;7506.297732816072;7677.539347639159;7192.31672991065;7345.926540734738;7386.778396648109;7515.098188217449;7525.623718813928;6967.198772141269;6994.545354551938;7070.505064645426;7070.893936590853;7074.540154889915;4920.704757714406;4956.083124621957;4975.925361119658;5002.774875130555;5072.315237442898;5046.747662616781;5091.727068232942;5137.717425541481;5154.768035438489;5251.789928402821 10:39:12 INFO - PROCESS | 1662 | |4;prototype.html;267.95042917060346;296.8649264584614;306.9570260163577;313.92779660678053;314.9763269374533;4777.880552986168;5339.8398048058525;5354.759035843386;5371.731413429327;5407.891842163154;4922.483139270379;4998.875477921878;5089.821856235032;5181.896362072765;5313.787448502065;3660.3777357849094;3841.673457756094;4051.0682543015123;4087.550369459372;4097.897552561188;4074.327735923584;4091.8363265469347;4096.365063415175;4096.67226621871;4098.959010409888;3247.33429646923;3754.967383969403;3925.998870288087;4067.4508941292843;4073.368627862686;4006.3389540725743;4012.7391719538136;4048.332025215836;4052.230076285496;4080.999999999985;3540.7521473496868;3541.2386336937425;3541.29174165168;3546.8758593449365;3570.821458927069;4796.688215266032;5107.463716309771;5125.769340379692;5131.281620573122;5147.7683504242395;3712.795796231206;3815.9427608585893;4058.857939972116;4626.930596041062;4632.745199014053;4789.425268967746;5441.483059109379;5489.4236105209175;5495.230667706524;5620.016497112989;3579.928401431983;3877.875416129319;3905.453236546886;3929.3713005918917;3981.303271927401;4960.429550601691;5828.388019257959;5856.209411729447;5857.121431785181;5883.470487656132;3398.4222682144095;3829.5404551454003;3855.363864962306;3873.7482063666334;3882.9223415531533;3660.9864574483927;3891.2023035278266;3935.370340745467;3945.467361906163;3950.367941129463;4158.4178215049915;4396.890077748018;4537.931931021038;4572.97713511437;4600.332951721982;5341.958318128006;5515.172724091419;5597.804076857372;5603.066941905036;5603.515068506849;1131.864176298833;1157.739508610556;1218.6648671615376;1263.6920786985427;1344.8117263583106;829.2992421403904;847.8050048488882;852.654674856684;874.4796845876693;892.8616014581331;5076.395759011143;5089.923651145235;5101.642884998015;5140.997505486469;5182.2485739567555;5543.473370029841;5695;5701.486866182064;5746.770129194869;5804.767809287581;4618.630509559227;4828.633969354991;4867.245576935581;4933.28467372229;5169.793208271627;2618.9214323570322;2778.6384671510814;2785.3454438207004;2792.9247239812817;2899.159243819311;5455.66336247614;5962.674556922803;5972.522198224132;5979.701014949235;6049;5113.744312784346;5834;5860.388393191906;5895.204147440127;5903.793367232126;5105.948940510547;5885.352611212848;5898.498627616701;5916.26046744157;5938.138969849434;4974.5771609413605;5018.397792264952;5094.490550945025;5701.743421545957;5796.594238403271;4815.927761083657;5017.724025178504;5128.461511541234;5452.318460192476;5687.943120568907;5590.916136258041;5840.094785308284;5917.952254588976;5927.644341339534;5960.748242868873;3508.0528257369856;3653.5067765851268;3734.7572407793405;3832.6742226911024;3881.3013657541915;4816.686915350491;5016.673916195436;5645.943540564542;5692;5714.342850572071;4677.976610116928;5138.8972220556125;5502.642328248651;5527.585431092603;5534.280543529316;4441.822327106879;5147.382314122179;5496.780128794803;5511.53151982086;5527.723613819373;4916.532929371704;5206.791728330824;5344.6525975813;5365;5410.891782164414;5052.343195384577;5352.929414117114;5364.919526207032;5431.9185212221055;5676.035074037341;5050.898982020265;5392.379876314118;5494.725263736877;5499.587530935276;5515.420880807618 10:39:12 INFO - PROCESS | 1662 | |5;yui.html;743.2790193512293;750.2572453271259;767.463533023358;788.1763557082847;830.7175560309502;15496.288149573822;15557.299921503545;15812.44656437025;15838.841611583895;15866.682666346665;13955.720885582283;14110.223937683424;14419.351129199185;14651.853481465183;14676.266186690676;719.0795781399817;742.4394582090514;890.1321211818457;899.3839220134229;902.5306840442983;879.6877108626442;890.8930928288614;894.8612964990405;910.41733290694;917.9810410444435;976.623999760094;984.2372161574765;986.4968865878418;988.4069558265055;989.9703008909709;752.1688534169718;942.7313215733483;979.1921664626711;979.8138353712772;984.4880662055726;673.5924765516841;675.8884784010681;677.9932200677979;679.2494293805323;683.9110915580943;15049.021813582081;15081.924590376977;15103.924480377638;15130.773038404433;15140.470083547134;13804.03371763977;13971.091879027932;14012.369443375073;14106.576802695936;14288.142711437347;10913.508892099875;12660.17708848922;12662.05034622407;12706;12748.235105893677;4521.525239849801;4584.610308123813;4608.677392582521;4701.576858082789;4770.952290477086;103533;103931;106502;107102.92897071007;108992;10474.690663667041;11199.888001120047;11221.831672524919;11275.66173014811;11356.886431135748;20037.899810501145;22208.88955552216;22213.55572888533;22245.22141725032;22271.77728222697;7900.644470998819;7988.360931125496;8099.190080991853;8122.959385203154;8324.500529968222;3583.9248225532237;3618.782873027627;3838.8272527736317;4404.163208990281;4452.243118669834;3420;3530.470429435554;3530.91172720684;3537.5401197844117;3557.217412169319;540.8026070484245;629.8488362792897;646.6411141816337;654.7675575170841;655.6133777061576;595.4968051996057;616.4729156571157;630.4389093706604;637.9521535884827;656.4256275758711;9058.275337972946;9092.818143637223;9092.909070909338;9148.96910562608;9205.309601779893;575.7581815637359;599.3497055694532;603.8882806680778;639.7141745092398;643.7392855893371;4301.892452688645;4396.4064851244675;4453.606604722476;4469.4860091089295;4602.539746025371;122091;158082;163052;163860.54209186873;171039;133214.33392832972;134916;135774;136034.3198284002;139123;74559;74971.62514187394;77450.73732855919;77521.61239193768;102806.93747147267;8225.588720563828;8905.421147625384;8967.506787126771;8967.730968070968;9059.773505662148;61018.69490652696;63153;63957;64244.07267781974;64248.67875660592;72029;91464;99326.00673993168;100007.49996249973;100405.99188016345;13538;13543.458261669422;13577.728445431234;13594.592162235149;13666.590002299947;14846.331915064064;14864.85135148678;14965.102093874402;15015.474458393905;15873.365065397255;9863.352061414447;12516.436760345989;13579.52471663487;13882.722345553235;14211;13902.51341203053;13955.58133256004;13993.650158745706;14133.858661413666;14637.26813659334;13595.184288942695;13648.65878353049;13707.725845483234;13928.72142557123;14264.286785660468;11089.88910110921;11305.37820419852;11515.25150865191;11614.6515604532;11953.701157470785;11158.55365785392;11353.205275630957;11578.942105289756;11610.593629223275;11625.18623696333 10:39:12 INFO - PROCESS | 1662 | __end_tp_report 10:39:12 INFO - PROCESS | 1662 | __start_cc_report 10:39:12 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1524 10:39:12 INFO - PROCESS | 1662 | __end_cc_report 10:39:12 INFO - PROCESS | 1662 | __startTimestamp1464889152968__endTimestamp 10:39:12 INFO - PROCESS | 1662 | 10:39:12 INFO - PROCESS | 1662 | ------- Summary: start ------- 10:39:12 INFO - PROCESS | 1662 | Number of tests: 6 10:39:12 INFO - PROCESS | 1662 | 10:39:12 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5778.70 Median:6014.10 stddev:653.95 (10.9%) stddev-sans-first:631.45 10:39:12 INFO - PROCESS | 1662 | Values: 3517.7 3522.1 3532.0 3541.3 3606.5 5658.3 5746.5 5777.4 5798.6 5845.7 5751.6 5786.2 5843.8 5868.7 5904.4 4733.2 4781.7 5154.0 5238.4 5340.8 5333.4 5367.9 5372.6 5404.4 5430.0 5176.4 5219.1 5230.2 5269.2 5293.2 5283.1 5293.3 5295.3 5303.2 5308.0 4283.8 5174.2 5225.0 5229.6 5251.0 4701.1 5661.8 5738.9 5768.3 5769.7 6274.2 6287.3 6331.9 6333.0 6345.0 5232.4 6161.8 6241.9 6351.0 6374.0 4843.8 5825.6 5905.9 5940.8 5945.1 6340.7 6345.7 6363.4 6364.5 6372.7 6052.2 6103.9 6139.2 6200.8 6219.1 6267.8 6343.2 6345.1 6348.4 6355.4 6237.4 6262.1 6272.7 6275.0 6287.5 5236.2 5988.9 6086.0 6172.1 6187.0 4975.1 4979.5 4997.4 5008.4 5047.4 4472.2 4479.2 4480.2 4511.1 4539.1 5638.6 5691.9 5699.3 5772.9 5785.3 6314.1 6350.5 6358.5 6361.1 6363.0 5783.7 5786.7 5787.1 5799.8 5843.9 5355.2 5365.3 5432.9 5474.8 5483.0 6370.7 6431.3 6468.7 6469.9 6484.8 6320.7 6339.1 6361.9 6369.1 6385.8 5594.0 6268.7 6328.3 6399.5 6457.2 6261.6 6292.4 6299.1 6300.0 6300.8 6286.0 6293.5 6331.7 6344.6 6345.8 5987.0 6041.7 6114.4 6214.0 6216.2 6104.7 6188.2 6208.2 6214.6 6223.1 6042.4 6043.5 6061.4 6069.6 6119.6 5937.6 6040.0 6207.8 6215.8 6224.8 5959.2 6014.1 6015.4 6033.7 6053.3 10:39:12 INFO - PROCESS | 1662 | 10:39:12 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:72775.90 Median:19523.98 stddev:162385.66 (831.7%) stddev-sans-first:162750.50 10:39:12 INFO - PROCESS | 1662 | Values: 489.8 493.8 500.3 507.3 527.8 31073.4 42376.2 42836.4 43007.8 45774.3 38183.6 43441.6 43977.0 44432.0 44475.0 8639.3 9398.6 9625.2 9632.5 9638.6 11432.3 11752.7 11762.5 12041.9 12060.8 11604.1 11720.3 11738.4 11740.2 11749.5 8572.6 8783.1 8783.3 11689.9 11846.8 8731.7 11315.8 11707.0 11709.4 11960.8 33594.0 41947.0 41953.6 42021.6 42068.6 2989.5 3481.4 3484.3 3531.7 3643.9 32397.8 32406.8 32447.8 32598.3 33433.5 2847.8 3236.5 3239.1 3244.0 3339.6 512285.0 513642.0 513776.0 513822.0 514172.0 60928.7 60972.0 61486.4 61520.4 61751.7 34347.7 34384.3 35516.3 36992.0 65020.7 16844.0 16902.3 17030.2 17037.0 17072.9 11762.0 11847.7 12905.2 12990.1 13246.5 19851.2 20113.0 20154.1 20156.5 20184.9 6769.0 7483.0 7784.6 7837.5 7886.4 881.5 991.3 1038.7 1044.6 1081.5 29968.0 30535.8 30765.2 30829.3 31147.5 953.5 1050.0 1072.7 1097.6 1174.3 15072.5 15314.2 15354.4 15716.4 15785.1 697572.0 703824.0 703884.0 705526.0 706637.0 60032.7 60545.1 60625.0 60688.0 60710.0 1279.9 1396.9 1408.1 1412.2 1451.9 28031.3 30926.9 31013.8 31284.7 31462.5 51652.5 52459.0 52488.7 52575.0 52615.0 545092.0 596204.0 597772.0 597887.0 598801.0 23439.6 25302.2 25974.7 26197.5 26429.9 40563.6 47219.5 47756.0 48361.8 48918.5 36703.0 38317.8 38419.2 38876.4 39096.4 37862.6 38336.6 38452.0 38862.8 39343.6 37241.4 37905.6 38043.2 38275.4 38297.4 32724.0 33218.8 33470.0 33694.3 34368.0 21802.7 25193.5 25213.2 25613.7 25919.5 10:39:12 INFO - PROCESS | 1662 | 10:39:12 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:35669.08 Median:14820.22 stddev:103109.61 (695.7%) stddev-sans-first:103364.77 10:39:12 INFO - PROCESS | 1662 | Values: 361.3 379.4 383.6 396.5 398.7 15483.1 16011.6 19784.0 22223.1 22234.8 20150.7 21010.9 21372.0 21575.7 21694.6 5735.6 5801.7 6038.8 6041.1 6066.0 4150.8 4384.6 5926.0 6131.6 6497.3 6338.0 6394.1 6440.5 6445.6 6446.7 6289.4 6432.0 6435.6 6436.4 6451.9 5425.2 5601.5 5658.8 5674.5 5695.4 23901.2 25042.2 25333.6 26995.9 27151.3 2123.5 2261.5 2529.3 2644.6 2859.4 9968.4 10024.7 10042.5 10366.3 10372.1 1862.8 1915.8 1939.1 1939.6 1949.3 97978.0 119435.6 125136.0 125777.0 126709.4 9258.4 9980.4 11252.8 11548.7 11684.5 14927.5 14939.5 14952.2 14953.7 14992.7 7256.7 8379.2 8410.3 8580.1 8662.1 8416.5 8418.7 8504.9 8576.1 8685.3 4583.7 4617.8 4624.1 4628.4 4690.8 1573.5 1607.5 1725.9 1772.5 1916.6 923.6 943.2 946.7 964.7 982.1 20320.4 21452.6 21507.4 21627.2 21880.3 887.8 958.7 981.3 1009.9 1019.3 8995.9 10730.2 11333.8 11351.6 12250.9 560937.0 621147.0 627202.0 635926.0 636878.0 135750.0 142092.0 143050.0 143190.0 143427.0 982.4 1007.2 1030.7 1032.7 1033.4 17690.6 18595.4 18896.0 18998.0 19080.6 48094.3 48625.0 49778.8 50460.0 50897.2 53991.7 71172.6 71650.6 73835.6 74826.3 7973.8 8163.2 8197.2 8266.5 8441.0 9110.6 12575.7 12906.4 13457.5 13513.3 8919.4 11510.3 11703.5 11713.9 11967.2 11471.4 11508.5 11514.7 11690.4 11828.1 11622.4 11629.1 11857.9 11967.8 11986.7 8241.7 8437.5 8446.2 8507.1 8557.4 11268.3 11420.3 11573.8 11630.8 11729.5 10:39:12 INFO - PROCESS | 1662 | 10:39:12 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5626.48 Median:4924.38 stddev:3167.02 (64.3%) stddev-sans-first:3159.47 10:39:12 INFO - PROCESS | 1662 | Values: 1333.6 1535.3 1546.1 1782.2 1946.2 7882.7 8260.1 8268.0 8269.3 8272.5 7330.5 8426.8 8684.2 8699.3 8711.4 2341.4 2416.0 2416.8 2418.6 2420.4 2361.5 2386.5 2394.3 2398.7 2400.4 2359.8 2373.9 2382.9 2394.5 2397.0 2336.6 2342.6 2375.1 2375.7 2380.0 2370.5 2376.8 2403.4 2405.4 2406.9 8646.1 8696.7 8729.4 8735.3 8736.9 5150.4 5231.7 5323.3 5335.1 5421.9 3536.4 3646.6 3698.2 3722.4 3736.6 1042.8 1062.1 1073.4 1084.1 1085.6 11576.4 11683.4 11683.8 11688.4 11701.0 4319.6 4513.7 4613.7 4646.4 4652.8 9159.6 9160.2 9164.9 9167.4 9176.4 5239.3 5245.7 5247.2 5251.1 5253.1 4449.5 4595.6 4851.2 4923.9 4924.9 3248.8 3550.4 3558.0 3760.1 3778.8 1142.9 1161.5 1164.5 1168.4 1170.4 601.8 812.9 865.5 964.9 970.0 5608.2 5712.3 6005.3 6021.9 6043.0 463.1 469.6 470.6 487.3 626.9 3326.6 3875.5 3980.3 4079.6 4082.6 10355.8 10380.1 10729.9 10756.6 10761.6 10518.2 10711.6 10725.6 10738.7 10771.2 7879.5 8312.0 8316.0 8319.2 8332.4 4635.5 5816.6 5871.1 6017.0 6093.5 9559.7 9785.9 9799.5 10024.0 10032.7 9793.2 10141.7 10393.9 10396.5 10396.8 6937.4 7124.5 7143.7 7345.2 7673.2 7899.9 7982.4 8116.5 8287.0 8292.5 6509.0 7307.9 7504.6 7506.3 7677.5 7192.3 7345.9 7386.8 7515.1 7525.6 6967.2 6994.5 7070.5 7070.9 7074.5 4920.7 4956.1 4975.9 5002.8 5072.3 5046.7 5091.7 5137.7 5154.8 5251.8 10:39:12 INFO - PROCESS | 1662 | 10:39:12 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4493.52 Median:5064.37 stddev:1387.28 (27.4%) stddev-sans-first:1354.43 10:39:12 INFO - PROCESS | 1662 | Values: 268.0 296.9 307.0 313.9 315.0 4777.9 5339.8 5354.8 5371.7 5407.9 4922.5 4998.9 5089.8 5181.9 5313.8 3660.4 3841.7 4051.1 4087.6 4097.9 4074.3 4091.8 4096.4 4096.7 4099.0 3247.3 3755.0 3926.0 4067.5 4073.4 4006.3 4012.7 4048.3 4052.2 4081.0 3540.8 3541.2 3541.3 3546.9 3570.8 4796.7 5107.5 5125.8 5131.3 5147.8 3712.8 3815.9 4058.9 4626.9 4632.7 4789.4 5441.5 5489.4 5495.2 5620.0 3579.9 3877.9 3905.5 3929.4 3981.3 4960.4 5828.4 5856.2 5857.1 5883.5 3398.4 3829.5 3855.4 3873.7 3882.9 3661.0 3891.2 3935.4 3945.5 3950.4 4158.4 4396.9 4537.9 4573.0 4600.3 5342.0 5515.2 5597.8 5603.1 5603.5 1131.9 1157.7 1218.7 1263.7 1344.8 829.3 847.8 852.7 874.5 892.9 5076.4 5089.9 5101.6 5141.0 5182.2 5543.5 5695.0 5701.5 5746.8 5804.8 4618.6 4828.6 4867.2 4933.3 5169.8 2618.9 2778.6 2785.3 2792.9 2899.2 5455.7 5962.7 5972.5 5979.7 6049.0 5113.7 5834.0 5860.4 5895.2 5903.8 5105.9 5885.4 5898.5 5916.3 5938.1 4974.6 5018.4 5094.5 5701.7 5796.6 4815.9 5017.7 5128.5 5452.3 5687.9 5590.9 5840.1 5918.0 5927.6 5960.7 3508.1 3653.5 3734.8 3832.7 3881.3 4816.7 5016.7 5645.9 5692.0 5714.3 4678.0 5138.9 5502.6 5527.6 5534.3 4441.8 5147.4 5496.8 5511.5 5527.7 4916.5 5206.8 5344.7 5365.0 5410.9 5052.3 5352.9 5364.9 5431.9 5676.0 5050.9 5392.4 5494.7 5499.6 5515.4 10:39:12 INFO - PROCESS | 1662 | 10:39:12 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:24481.37 Median:3547.38 stddev:39289.24 (1107.6%) stddev-sans-first:39359.03 10:39:12 INFO - PROCESS | 1662 | Values: 743.3 750.3 767.5 788.2 830.7 15496.3 15557.3 15812.4 15838.8 15866.7 13955.7 14110.2 14419.4 14651.9 14676.3 719.1 742.4 890.1 899.4 902.5 879.7 890.9 894.9 910.4 918.0 976.6 984.2 986.5 988.4 990.0 752.2 942.7 979.2 979.8 984.5 673.6 675.9 678.0 679.2 683.9 15049.0 15081.9 15103.9 15130.8 15140.5 13804.0 13971.1 14012.4 14106.6 14288.1 10913.5 12660.2 12662.1 12706.0 12748.2 4521.5 4584.6 4608.7 4701.6 4771.0 103533.0 103931.0 106502.0 107102.9 108992.0 10474.7 11199.9 11221.8 11275.7 11356.9 20037.9 22208.9 22213.6 22245.2 22271.8 7900.6 7988.4 8099.2 8123.0 8324.5 3583.9 3618.8 3838.8 4404.2 4452.2 3420.0 3530.5 3530.9 3537.5 3557.2 540.8 629.8 646.6 654.8 655.6 595.5 616.5 630.4 638.0 656.4 9058.3 9092.8 9092.9 9149.0 9205.3 575.8 599.3 603.9 639.7 643.7 4301.9 4396.4 4453.6 4469.5 4602.5 122091.0 158082.0 163052.0 163860.5 171039.0 133214.3 134916.0 135774.0 136034.3 139123.0 74559.0 74971.6 77450.7 77521.6 102806.9 8225.6 8905.4 8967.5 8967.7 9059.8 61018.7 63153.0 63957.0 64244.1 64248.7 72029.0 91464.0 99326.0 100007.5 100406.0 13538.0 13543.5 13577.7 13594.6 13666.6 14846.3 14864.9 14965.1 15015.5 15873.4 9863.4 12516.4 13579.5 13882.7 14211.0 13902.5 13955.6 13993.7 14133.9 14637.3 13595.2 13648.7 13707.7 13928.7 14264.3 11089.9 11305.4 11515.3 11614.7 11953.7 11158.6 11353.2 11578.9 11610.6 11625.2 10:39:12 INFO - PROCESS | 1662 | -------- Summary: end -------- 10:39:12 INFO - PROCESS | 1662 | 10:39:13 INFO - TEST-INFO | 1662: exit 0 10:39:13 INFO - TEST-OK | dromaeo_css | took 1116244ms 10:39:13 INFO - TEST-START | kraken 10:39:13 INFO - operating with platform_type : mac_ 10:39:13 INFO - Initialising browser for kraken test... 10:39:13 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/tmpgkVQXW/profile http://localhost:49275/getInfo.html) 10:39:15 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 10:39:15 INFO - PROCESS | 1672 | colorDepth:24 10:39:15 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 10:39:15 INFO - PROCESS | 1672 | __metrics 10:39:16 INFO - PROCESS | 1672 | 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" 10:39:16 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:39:16 INFO - TEST-INFO | 1672: exit 0 10:39:16 INFO - Browser initialized. 10:39:16 INFO - Running cycle 1/1 for kraken test... 10:39:16 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/tmpgkVQXW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:39:28 INFO - PROCESS | 1673 | RSS: Main: 242204672 10:39:28 INFO - PROCESS | 1673 | 10:39:31 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 10:39:32 INFO - PROCESS | 1673 | RSS: Main: 292405248 10:39:32 INFO - PROCESS | 1673 | 10:39:34 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 10:39:35 INFO - PROCESS | 1673 | RSS: Main: 303325184 10:39:35 INFO - PROCESS | 1673 | 10:39:38 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 10:39:39 INFO - PROCESS | 1673 | RSS: Main: 388489216 10:39:39 INFO - PROCESS | 1673 | 10:39:41 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 10:39:42 INFO - PROCESS | 1673 | RSS: Main: 290373632 10:39:42 INFO - PROCESS | 1673 | 10:39:44 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:39:44 INFO - PROCESS | 1673 | RSS: Main: 295002112 10:39:44 INFO - PROCESS | 1673 | 10:39:47 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 10:39:48 INFO - PROCESS | 1673 | RSS: Main: 328916992 10:39:48 INFO - PROCESS | 1673 | 10:39:51 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 10:39:51 INFO - PROCESS | 1673 | RSS: Main: 331694080 10:39:51 INFO - PROCESS | 1673 | 10:39:54 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 10:39:55 INFO - PROCESS | 1673 | RSS: Main: 330825728 10:39:55 INFO - PROCESS | 1673 | 10:39:56 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:39:57 INFO - PROCESS | 1673 | RSS: Main: 312586240 10:39:57 INFO - PROCESS | 1673 | 10:39:59 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:40:00 INFO - PROCESS | 1673 | RSS: Main: 319401984 10:40:00 INFO - PROCESS | 1673 | 10:40:02 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:40:02 INFO - PROCESS | 1673 | RSS: Main: 323784704 10:40:02 INFO - PROCESS | 1673 | 10:40:05 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:40:06 INFO - PROCESS | 1673 | RSS: Main: 326766592 10:40:06 INFO - PROCESS | 1673 | 10:40:09 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:40:09 INFO - PROCESS | 1673 | RSS: Main: 326377472 10:40:09 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 10:40:11 INFO - PROCESS | 1673 | RSS: Main: 370712576 10:40:11 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | __start_tp_report 10:40:11 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 10:40:11 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 10:40:11 INFO - PROCESS | 1673 | |i|pagename|runs| 10:40:11 INFO - PROCESS | 1673 | |0;ai-astar;112;106;112;113;112;112;112;112;113;113 10:40:11 INFO - PROCESS | 1673 | |1;audio-beat-detection;154;118;120;167;121;137;133;117;172;119 10:40:11 INFO - PROCESS | 1673 | |2;audio-dft;181;183;185;182;183;183;180;185;180;184 10:40:11 INFO - PROCESS | 1673 | |3;audio-fft;77;75;76;132;80;56;60;66;95;56 10:40:11 INFO - PROCESS | 1673 | |4;audio-oscillator;84;91;92;70;71;69;97;70;67;103 10:40:11 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;88;86;88;91;88;91;88;84;88;85 10:40:11 INFO - PROCESS | 1673 | |6;imaging-darkroom;93;93;94;94;93;93;99;95;88;149 10:40:11 INFO - PROCESS | 1673 | |7;imaging-desaturate;85;84;84;107;134;140;84;88;83;84 10:40:11 INFO - PROCESS | 1673 | |8;json-parse-financial;55;56;54;56;56;56;56;55;53;55 10:40:11 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;67;73;82;78;76;99;69;72;71;70 10:40:11 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;87;85;86;88;85;86;88;87;158;108 10:40:11 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;146;151;146;144;139;139;139;136;155;140 10:40:11 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;164;165;166;165;167;166;164;166;164;168 10:40:11 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;64;67;66;66;67;65;66;66;67;66 10:40:11 INFO - PROCESS | 1673 | __end_tp_report 10:40:11 INFO - PROCESS | 1673 | __start_cc_report 10:40:11 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1943 10:40:11 INFO - PROCESS | 1673 | __end_cc_report 10:40:11 INFO - PROCESS | 1673 | __startTimestamp1464889211797__endTimestamp 10:40:11 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | ------- Summary: start ------- 10:40:11 INFO - PROCESS | 1673 | Number of tests: 14 10:40:11 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:2.06 (1.8%) stddev-sans-first:2.18 10:40:11 INFO - PROCESS | 1673 | Values: 112.0 106.0 112.0 113.0 112.0 112.0 112.0 112.0 113.0 113.0 10:40:11 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:135.80 Median:135.00 stddev:21.20 (15.7%) stddev-sans-first:21.44 10:40:11 INFO - PROCESS | 1673 | Values: 154.0 118.0 120.0 167.0 121.0 137.0 133.0 117.0 172.0 119.0 10:40:11 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.00 stddev:1.84 (1.0%) stddev-sans-first:1.86 10:40:11 INFO - PROCESS | 1673 | Values: 181.0 183.0 185.0 182.0 183.0 183.0 180.0 185.0 180.0 184.0 10:40:11 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:77.30 Median:75.50 stddev:22.74 (30.1%) stddev-sans-first:24.12 10:40:11 INFO - PROCESS | 1673 | Values: 77.0 75.0 76.0 132.0 80.0 56.0 60.0 66.0 95.0 56.0 10:40:11 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:77.50 stddev:13.54 (17.5%) stddev-sans-first:14.33 10:40:11 INFO - PROCESS | 1673 | Values: 84.0 91.0 92.0 70.0 71.0 69.0 97.0 70.0 67.0 103.0 10:40:11 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:88.00 stddev:2.26 (2.6%) stddev-sans-first:2.40 10:40:11 INFO - PROCESS | 1673 | Values: 88.0 86.0 88.0 91.0 88.0 91.0 88.0 84.0 88.0 85.0 10:40:11 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:99.10 Median:93.50 stddev:17.74 (19.0%) stddev-sans-first:18.67 10:40:11 INFO - PROCESS | 1673 | Values: 93.0 93.0 94.0 94.0 93.0 93.0 99.0 95.0 88.0 149.0 10:40:11 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:97.30 Median:84.00 stddev:22.15 (26.4%) stddev-sans-first:23.04 10:40:11 INFO - PROCESS | 1673 | Values: 85.0 84.0 84.0 107.0 134.0 140.0 84.0 88.0 83.0 84.0 10:40:11 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:56.00 stddev:1.03 (1.8%) stddev-sans-first:1.09 10:40:11 INFO - PROCESS | 1673 | Values: 55.0 56.0 54.0 56.0 56.0 56.0 56.0 55.0 53.0 55.0 10:40:11 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:75.70 Median:74.50 stddev:9.33 (12.5%) stddev-sans-first:9.35 10:40:11 INFO - PROCESS | 1673 | Values: 67.0 73.0 82.0 78.0 76.0 99.0 69.0 72.0 71.0 70.0 10:40:11 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:95.80 Median:86.50 stddev:22.90 (26.5%) stddev-sans-first:24.07 10:40:11 INFO - PROCESS | 1673 | Values: 87.0 85.0 86.0 88.0 85.0 86.0 88.0 87.0 158.0 108.0 10:40:11 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:143.50 Median:145.00 stddev:6.06 (4.2%) stddev-sans-first:6.36 10:40:11 INFO - PROCESS | 1673 | Values: 146.0 151.0 146.0 144.0 139.0 139.0 139.0 136.0 155.0 140.0 10:40:11 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.50 Median:166.00 stddev:1.35 (0.8%) stddev-sans-first:1.32 10:40:11 INFO - PROCESS | 1673 | Values: 164.0 165.0 166.0 165.0 167.0 166.0 164.0 166.0 164.0 168.0 10:40:11 INFO - PROCESS | 1673 | 10:40:11 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:0.94 (1.4%) stddev-sans-first:0.67 10:40:11 INFO - PROCESS | 1673 | Values: 64.0 67.0 66.0 66.0 67.0 65.0 66.0 66.0 67.0 66.0 10:40:11 INFO - PROCESS | 1673 | -------- Summary: end -------- 10:40:11 INFO - PROCESS | 1673 | 10:40:12 INFO - TEST-INFO | 1673: exit 0 10:40:12 INFO - TEST-OK | kraken | took 58965ms 10:40:12 INFO - SUITE-END | took 1175s 10:40:12 INFO - Completed test suite (00:19:35) 10:40:12 INFO - javascript benchmark 10:40:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3517.7361697872657, 3522.1370764162784, 3531.9646803531964, 3541.3271478419083, 3606.5311509503777, 5658.264425624663, 5746.511546518545, 5777.42225777422, 5798.56510761693, 5845.7369418376165, 5751.56863235258, 5786.1610066540225, 5843.824685259438, 5868.706564671771, 5904.350521442638, 4733.242681171013, 4781.713097214179, 5154.0, 5238.4499627539135, 5340.759665815048, 5333.38666053403, 5367.892642147155, 5372.57019438444, 5404.378496472917, 5429.972850135744, 5176.352955880515, 5219.112750832368, 5230.189320655267, 5269.1832765921345, 5293.232481290192, 5283.075461794209, 5293.311869456946, 5295.258663787073, 5303.204519322094, 5308.0, 4283.764392958386, 5174.24973378858, 5225.0, 5229.607779416559, 5251.002309561171, 4701.106789709944, 5661.801836935688, 5738.885222295531, 5768.250127483442, 5769.711514424304, 6274.247090349141, 6287.276963149251, 6331.905021424683, 6332.968335158294, 6344.968275158641, 5232.372115346145, 6161.784337548209, 6241.906371404432, 6351.0, 6374.043893416025, 4843.757812109416, 5825.621334613236, 5905.881882362329, 5940.79207227745, 5945.10823376497, 6340.714667839959, 6345.7144428499905, 6363.427291543783, 6364.522660800459, 6372.745090196433, 6052.213212282463, 6103.877922441615, 6139.2325959255095, 6200.844978875564, 6219.098230756515, 6267.811965641038, 6343.207099112611, 6345.07996340528, 6348.428641422294, 6355.3644635537, 6237.376262373727, 6262.091996660459, 6272.717727702265, 6275.0, 6287.528435367366, 5236.214567814858, 5988.880222395528, 6086.02623580225, 6172.105044768484, 6187.0, 4975.0796102721115, 4979.545635078194, 4997.350344455271, 5008.424031236382, 5047.4447810740785, 4472.239719247736, 4479.171353299585, 4480.238359478896, 4511.142882852248, 4539.114872599877, 5638.605297629127, 5691.857703557362, 5699.2590963174525, 5772.855678607984, 5785.27684039495, 6314.084457753692, 6350.4602108820345, 6358.459530939829, 6361.077643741632, 6363.045543168561, 5783.739731712083, 5786.681732504669, 5787.131930210502, 5799.768009279666, 5843.941560584425, 5355.169948657965, 5365.302510673588, 5432.864178395572, 5474.808381706621, 5483.0, 6370.713317900706, 6431.260405053479, 6468.741250350122, 6469.870602587827, 6484.837879053061, 6320.683965801783, 6339.144215531029, 6361.904571431525, 6369.14016607752, 6385.808425747235, 5594.0, 6268.686565671789, 6328.303886572381, 6399.520035997226, 6457.160569125984, 6261.592996455216, 6292.402221788922, 6299.055141728777, 6300.0, 6300.84247893788, 6285.96857015712, 6293.465055470337, 6331.683415829282, 6344.619322840645, 6345.841353966188, 5986.952283350309, 6041.697915104315, 6114.449699527064, 6214.0, 6216.160818289654, 6104.701693046632, 6188.164597779242, 6208.2239720034995, 6214.564980451325, 6223.128761973237, 6042.3655516170165, 6043.516518678407, 6061.449321453728, 6069.605475644245, 6119.632822030692, 5937.584369094295, 6040.033594624839, 6207.782727604512, 6215.782447614311, 6224.813255602339, 5959.22530071088, 6014.097885317237, 6015.368386319374, 6033.698315084141, 6053.334133245427], "value": 5739.153311793955, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.76491284183567, 493.75312343828085, 500.34454864127986, 507.32272416324207, 527.8469243919262, 31073.37853242934, 42376.15247695048, 42836.357454638164, 43007.78496107523, 45774.31338529917, 38183.61816381842, 43441.56558434415, 43977.0, 44432.0, 44475.0, 8639.265662418702, 9398.577064032135, 9625.22998160149, 9632.518374081303, 9638.61445542181, 11432.314061156316, 11752.706182345424, 11762.529498820037, 12041.939790301036, 12060.758784824298, 11604.129690273221, 11720.296782193096, 11738.41307934604, 11740.236884602473, 11749.471273792657, 8572.571371431466, 8783.077776833405, 8783.34124940632, 11689.941550292237, 11846.8222976655, 8731.738047858575, 11315.83026254607, 11707.0, 11709.355985420798, 11960.760784784343, 33594.0, 41947.0, 41953.58046419527, 42021.57978420207, 42068.57931420677, 2989.491786396318, 3481.408160612702, 3484.303139372136, 3531.6644918732936, 3643.9453408198897, 32397.838010810032, 32406.837965810257, 32447.78862152032, 32598.348033039445, 33433.49849752256, 2847.8323887206147, 3236.514522821572, 3239.1092449576317, 3243.9586246013473, 3339.5825521809775, 512285.0, 513642.0, 513776.0, 513822.0, 514172.0, 60928.695356522934, 60972.0, 61486.38513614896, 61520.384796152364, 61751.69124154351, 34347.656523434445, 34384.31231375359, 35516.28967420637, 36992.0, 65020.67489662521, 16844.0, 16902.323907043825, 17030.233639486254, 17037.0, 17072.914635426743, 11762.0, 11847.703807404883, 12905.225686458654, 12990.090693651353, 13246.536371226961, 19851.20595176206, 20113.0, 20154.093065812074, 20156.496087597927, 20184.899075504527, 6768.984652302096, 7483.0, 7784.571848548271, 7837.531177679013, 7886.408519361071, 881.453498830729, 991.3209451525728, 1038.693585392311, 1044.5560636729513, 1081.4971038467152, 29968.0, 30535.847320763252, 30765.230869228002, 30829.307887234558, 31147.532787008215, 953.4946478366477, 1049.994750026245, 1072.6889202131451, 1097.5719469406938, 1174.307158776309, 15072.472463463726, 15314.157721325435, 15354.385824567116, 15716.371345146066, 15785.131817749905, 697572.0, 703824.0, 703884.0, 705526.0, 706637.0, 60032.699836500535, 60545.09182362268, 60625.0, 60688.0, 60710.0, 1279.8912092472244, 1396.8812650924785, 1408.1269612840106, 1412.187991904659, 1451.8765904898203, 28031.299217519725, 30926.917557885365, 31013.8449307752, 31284.687153128176, 31462.52806207863, 51652.483475164765, 52459.0, 52488.73755631197, 52575.0, 52615.0, 545092.0, 596204.0, 597772.0111399415, 597887.0, 598801.0059949846, 23439.648405273594, 25302.240932772045, 25974.74025259799, 26197.476050479265, 26429.867850660623, 40563.59436405598, 47219.527804722886, 47756.0, 48361.758191210225, 48918.51081489282, 36703.0, 38317.80841095888, 38419.16359237335, 38876.362927375936, 39096.41355379615, 37862.62137378591, 38336.616633833306, 38452.038699032746, 38862.80568597139, 39343.60656393514, 37241.44137837989, 37905.62094379021, 38043.2445944257, 38275.42586861255, 38297.425538616386, 32724.018279451655, 33218.833905831285, 33470.0, 33694.31528423618, 34368.0, 21802.672959905296, 25193.496130077663, 25213.24360269195, 25613.743862561136, 25919.48161036731], "value": 20311.67016213107, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.29727679663057, 379.3721391097734, 383.59913889984944, 396.49446955132186, 398.6651212981095, 15483.14842683652, 16011.599710007256, 19783.99999999998, 22223.111075556997, 22234.777652223474, 20150.69773953392, 21010.89494552525, 21372.039572299374, 21575.67636485454, 21694.566108677856, 5735.594779279082, 5801.680907550083, 6038.849028774293, 6041.063635136561, 6066.0, 4150.792460376984, 4384.627306678936, 5925.970370148143, 6131.63210207389, 6497.285298617148, 6337.968310158442, 6394.13679153313, 6440.484761219114, 6445.613263204223, 6446.709898054575, 6289.433950944437, 6432.0, 6435.646039467827, 6436.420722135007, 6451.93548064518, 5425.213344065089, 5601.495865372136, 5658.773649054033, 5674.460926212003, 5695.373508914016, 23901.163459279014, 25042.248732538053, 25333.61999570008, 26995.865020674966, 27151.321216969536, 2123.522207503315, 2261.5476904618977, 2529.3170843872235, 2644.576867701159, 2859.356644754934, 9968.352057116264, 10024.699259022242, 10042.497875106215, 10366.326188797704, 10372.066514013775, 1862.8323450889486, 1915.837153841925, 1939.0983192815409, 1939.6217737541185, 1949.3079956615481, 97978.0, 119435.61128777376, 125136.0, 125777.0, 126709.36645316807, 9258.444493330288, 9980.351277167107, 11252.762196158415, 11548.711282217844, 11684.532618695326, 14927.477538286108, 14939.477118300807, 14952.177630230224, 14953.700925981637, 14992.70014599702, 7256.673449694671, 8379.245867871921, 8410.32717382608, 8580.14198580137, 8662.090480499457, 8416.4950102994, 8418.663253469915, 8504.894363732676, 8576.1423857615, 8685.261752750961, 4583.702059366131, 4617.792199351037, 4624.098300831373, 4628.421447319085, 4690.788914498856, 1573.4571572807363, 1607.541850572581, 1725.9223334949957, 1772.5059716341261, 1916.559191385989, 923.6259314977444, 943.1747221181466, 946.7396465971775, 964.6720115160739, 982.0866594067443, 20320.390388288375, 21452.570948580942, 21507.354779356643, 21627.2430464933, 21880.343589692333, 887.7691800132012, 958.7411398922252, 981.254246772458, 1009.9141572966233, 1019.2814066083394, 8995.865062024075, 10730.2488825783, 11333.773324533464, 11351.602693905674, 12250.877491224974, 560937.0, 621147.0, 627202.0, 635926.0, 636878.0, 135750.0, 142092.0, 143050.0, 143190.0, 143427.0, 982.4007355513131, 1007.2395341517254, 1030.7423144213947, 1032.695354870315, 1033.395463653756, 17690.5577360567, 18595.44213673592, 18896.0, 18998.0, 19080.618387631894, 48094.27858582194, 48625.0, 49778.751106244235, 50460.0, 50897.23654145265, 53991.73004134954, 71172.64413677898, 71650.64174679093, 73835.63082184555, 74826.25173748193, 7973.760787176394, 8163.224493673091, 8197.221263980151, 8266.504009759434, 8441.0, 9110.590023449093, 12575.748485030432, 12906.41921113571, 13457.461701531829, 13513.256770877697, 8919.42023768453, 11510.251833630788, 11703.473343699725, 11713.887180717818, 11967.162298639012, 11471.369074700971, 11508.48211830453, 11514.712132196428, 11690.357030363071, 11828.053755699351, 11622.418879055844, 11629.069674426195, 11857.881421185677, 11967.820482692592, 11986.700332491757, 8241.711540096068, 8437.493750374997, 8446.155384461505, 8507.064222935349, 8557.35819813504, 11268.267562608404, 11420.257683250562, 11573.768524629291, 11630.767384652429, 11729.472173752372], "value": 9657.048538082387, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.6332508560138, 1535.255401130452, 1546.1341648676741, 1782.2158250369837, 1946.2215113954408, 7882.724104656339, 8260.132686067951, 8268.049174344953, 8269.25576698097, 8272.50364978101, 7330.486865919388, 8426.789330266747, 8684.218420342168, 8699.347548933823, 8711.43375680582, 2341.4029422497238, 2415.9637605435933, 2416.7824895759377, 2418.613021916494, 2420.407000284924, 2361.456864921069, 2386.4607328273255, 2394.281715485356, 2398.7481314461993, 2400.411899084727, 2359.8112151027876, 2373.9169129080487, 2382.857028578282, 2394.5091256292503, 2396.9640455393182, 2336.6378211377178, 2342.5900467418305, 2375.10933399975, 2375.738668746445, 2380.0126894043497, 2370.454795397051, 2376.7742064503846, 2403.447207142367, 2405.4226985523346, 2406.8796560172095, 8646.135386461367, 8696.652133914698, 8729.388942774009, 8735.301175905912, 8736.8689469658, 5150.356205474316, 5231.686098834044, 5323.254744335796, 5335.093034184198, 5421.864453388657, 3536.4341705327283, 3646.6171052039685, 3698.1863989922176, 3722.404415293567, 3736.6076561960876, 1042.7591166511827, 1062.0866055192528, 1073.3988966178965, 1084.0893649334519, 1085.582050910405, 11576.42117894102, 11683.357415342238, 11683.824742628867, 11688.357140357277, 11701.0, 4319.568043195718, 4513.729176249435, 4613.723176609415, 4646.4191976003, 4652.790624421909, 9159.587818548182, 9160.22138118254, 9164.8625270621, 9167.404118732262, 9176.357654964222, 5239.345081864767, 5245.7114858683135, 5247.212918062245, 5251.081060814342, 5253.054450199001, 4449.532799056117, 4595.609373203315, 4851.248056551241, 4923.90152196954, 4924.852254432372, 3248.82131482766, 3550.431930891045, 3558.0, 3760.0787806987464, 3778.7543809652284, 1142.9142814288962, 1161.512164890748, 1164.5225457562528, 1168.4041139019162, 1170.373849990251, 601.7713268957768, 812.9349652027942, 865.5455885659953, 964.8986856380119, 969.9718298603503, 5608.1587761835235, 5712.285964254468, 6005.279366476051, 6021.879562408727, 6042.969785151046, 463.0622988448419, 469.61960811742597, 470.5929371094048, 487.32018833726755, 626.9467294944537, 3326.600807903067, 3875.5155605549307, 3980.3233450313514, 4079.551249362568, 4082.5713300103657, 10355.792884142274, 10380.117689996285, 10729.946350268356, 10756.623518176983, 10761.56953721858, 10518.211134164969, 10711.625093121704, 10725.624603138853, 10738.677839664822, 10771.192160587987, 7879.527228366316, 8312.044114926739, 8315.95842020786, 8319.168083191633, 8332.416730828783, 4635.466921304093, 5816.621919575214, 5871.089981052944, 6016.969915150571, 6093.542984276109, 9559.665411710557, 9785.853212201964, 9799.461029643438, 10024.0, 10032.749181270234, 9793.216542676711, 10141.695749127539, 10393.9480302598, 10396.532156052845, 10396.792064158826, 6937.410320122846, 7124.501284910007, 7143.714251429885, 7345.155307139534, 7673.155952845294, 7899.881501777363, 7982.441229113906, 8116.513009219466, 8286.964129483815, 8292.502449853027, 6508.967455162694, 7307.926920730724, 7504.62476876165, 7506.297732816072, 7677.539347639159, 7192.31672991065, 7345.926540734738, 7386.778396648109, 7515.098188217449, 7525.623718813928, 6967.198772141269, 6994.545354551938, 7070.505064645426, 7070.893936590853, 7074.540154889915, 4920.704757714406, 4956.083124621957, 4975.925361119658, 5002.774875130555, 5072.315237442898, 5046.747662616781, 5091.727068232942, 5137.717425541481, 5154.768035438489, 5251.789928402821], "value": 4457.186360510906, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [267.95042917060346, 296.8649264584614, 306.9570260163577, 313.92779660678053, 314.9763269374533, 4777.880552986168, 5339.8398048058525, 5354.759035843386, 5371.731413429327, 5407.891842163154, 4922.483139270379, 4998.875477921878, 5089.821856235032, 5181.896362072765, 5313.787448502065, 3660.3777357849094, 3841.673457756094, 4051.0682543015123, 4087.550369459372, 4097.897552561188, 4074.327735923584, 4091.8363265469347, 4096.365063415175, 4096.67226621871, 4098.959010409888, 3247.33429646923, 3754.967383969403, 3925.998870288087, 4067.4508941292843, 4073.368627862686, 4006.3389540725743, 4012.7391719538136, 4048.332025215836, 4052.230076285496, 4080.999999999985, 3540.7521473496868, 3541.2386336937425, 3541.29174165168, 3546.8758593449365, 3570.821458927069, 4796.688215266032, 5107.463716309771, 5125.769340379692, 5131.281620573122, 5147.7683504242395, 3712.795796231206, 3815.9427608585893, 4058.857939972116, 4626.930596041062, 4632.745199014053, 4789.425268967746, 5441.483059109379, 5489.4236105209175, 5495.230667706524, 5620.016497112989, 3579.928401431983, 3877.875416129319, 3905.453236546886, 3929.3713005918917, 3981.303271927401, 4960.429550601691, 5828.388019257959, 5856.209411729447, 5857.121431785181, 5883.470487656132, 3398.4222682144095, 3829.5404551454003, 3855.363864962306, 3873.7482063666334, 3882.9223415531533, 3660.9864574483927, 3891.2023035278266, 3935.370340745467, 3945.467361906163, 3950.367941129463, 4158.4178215049915, 4396.890077748018, 4537.931931021038, 4572.97713511437, 4600.332951721982, 5341.958318128006, 5515.172724091419, 5597.804076857372, 5603.066941905036, 5603.515068506849, 1131.864176298833, 1157.739508610556, 1218.6648671615376, 1263.6920786985427, 1344.8117263583106, 829.2992421403904, 847.8050048488882, 852.654674856684, 874.4796845876693, 892.8616014581331, 5076.395759011143, 5089.923651145235, 5101.642884998015, 5140.997505486469, 5182.2485739567555, 5543.473370029841, 5695.0, 5701.486866182064, 5746.770129194869, 5804.767809287581, 4618.630509559227, 4828.633969354991, 4867.245576935581, 4933.28467372229, 5169.793208271627, 2618.9214323570322, 2778.6384671510814, 2785.3454438207004, 2792.9247239812817, 2899.159243819311, 5455.66336247614, 5962.674556922803, 5972.522198224132, 5979.701014949235, 6049.0, 5113.744312784346, 5834.0, 5860.388393191906, 5895.204147440127, 5903.793367232126, 5105.948940510547, 5885.352611212848, 5898.498627616701, 5916.26046744157, 5938.138969849434, 4974.5771609413605, 5018.397792264952, 5094.490550945025, 5701.743421545957, 5796.594238403271, 4815.927761083657, 5017.724025178504, 5128.461511541234, 5452.318460192476, 5687.943120568907, 5590.916136258041, 5840.094785308284, 5917.952254588976, 5927.644341339534, 5960.748242868873, 3508.0528257369856, 3653.5067765851268, 3734.7572407793405, 3832.6742226911024, 3881.3013657541915, 4816.686915350491, 5016.673916195436, 5645.943540564542, 5692.0, 5714.342850572071, 4677.976610116928, 5138.8972220556125, 5502.642328248651, 5527.585431092603, 5534.280543529316, 4441.822327106879, 5147.382314122179, 5496.780128794803, 5511.53151982086, 5527.723613819373, 4916.532929371704, 5206.791728330824, 5344.6525975813, 5365.0, 5410.891782164414, 5052.343195384577, 5352.929414117114, 5364.919526207032, 5431.9185212221055, 5676.035074037341, 5050.898982020265, 5392.379876314118, 5494.725263736877, 5499.587530935276, 5515.420880807618], "value": 4043.360175986035, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.2790193512293, 750.2572453271259, 767.463533023358, 788.1763557082847, 830.7175560309502, 15496.288149573822, 15557.299921503545, 15812.44656437025, 15838.841611583895, 15866.682666346665, 13955.720885582283, 14110.223937683424, 14419.351129199185, 14651.853481465183, 14676.266186690676, 719.0795781399817, 742.4394582090514, 890.1321211818457, 899.3839220134229, 902.5306840442983, 879.6877108626442, 890.8930928288614, 894.8612964990405, 910.41733290694, 917.9810410444435, 976.623999760094, 984.2372161574765, 986.4968865878418, 988.4069558265055, 989.9703008909709, 752.1688534169718, 942.7313215733483, 979.1921664626711, 979.8138353712772, 984.4880662055726, 673.5924765516841, 675.8884784010681, 677.9932200677979, 679.2494293805323, 683.9110915580943, 15049.021813582081, 15081.924590376977, 15103.924480377638, 15130.773038404433, 15140.470083547134, 13804.03371763977, 13971.091879027932, 14012.369443375073, 14106.576802695936, 14288.142711437347, 10913.508892099875, 12660.17708848922, 12662.05034622407, 12706.0, 12748.235105893677, 4521.525239849801, 4584.610308123813, 4608.677392582521, 4701.576858082789, 4770.952290477086, 103533.0, 103931.0, 106502.0, 107102.92897071007, 108992.0, 10474.690663667041, 11199.888001120047, 11221.831672524919, 11275.66173014811, 11356.886431135748, 20037.899810501145, 22208.88955552216, 22213.55572888533, 22245.22141725032, 22271.77728222697, 7900.644470998819, 7988.360931125496, 8099.190080991853, 8122.959385203154, 8324.500529968222, 3583.9248225532237, 3618.782873027627, 3838.8272527736317, 4404.163208990281, 4452.243118669834, 3420.0, 3530.470429435554, 3530.91172720684, 3537.5401197844117, 3557.217412169319, 540.8026070484245, 629.8488362792897, 646.6411141816337, 654.7675575170841, 655.6133777061576, 595.4968051996057, 616.4729156571157, 630.4389093706604, 637.9521535884827, 656.4256275758711, 9058.275337972946, 9092.818143637223, 9092.909070909338, 9148.96910562608, 9205.309601779893, 575.7581815637359, 599.3497055694532, 603.8882806680778, 639.7141745092398, 643.7392855893371, 4301.892452688645, 4396.4064851244675, 4453.606604722476, 4469.4860091089295, 4602.539746025371, 122091.0, 158082.0, 163052.0, 163860.54209186873, 171039.0, 133214.33392832972, 134916.0, 135774.0, 136034.3198284002, 139123.0, 74559.0, 74971.62514187394, 77450.73732855919, 77521.61239193768, 102806.93747147267, 8225.588720563828, 8905.421147625384, 8967.506787126771, 8967.730968070968, 9059.773505662148, 61018.69490652696, 63153.0, 63957.0, 64244.07267781974, 64248.67875660592, 72029.0, 91464.0, 99326.00673993168, 100007.49996249973, 100405.99188016345, 13538.0, 13543.458261669422, 13577.728445431234, 13594.592162235149, 13666.590002299947, 14846.331915064064, 14864.85135148678, 14965.102093874402, 15015.474458393905, 15873.365065397255, 9863.352061414447, 12516.436760345989, 13579.52471663487, 13882.722345553235, 14211.0, 13902.51341203053, 13955.58133256004, 13993.650158745706, 14133.858661413666, 14637.26813659334, 13595.184288942695, 13648.65878353049, 13707.725845483234, 13928.72142557123, 14264.286785660468, 11089.88910110921, 11305.37820419852, 11515.25150865191, 11614.6515604532, 11953.701157470785, 11158.55365785392, 11353.205275630957, 11578.942105289756, 11610.593629223275, 11625.18623696333], "value": 7856.895301351876, "unit": "score"}], "name": "dromaeo_css", "value": 7363.597718306933, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 112.0, 113.0, 112.0, 112.0, 112.0, 112.0, 113.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 118.0, 120.0, 167.0, 121.0, 137.0, 133.0, 117.0, 172.0, 119.0], "value": 135.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 183.0, 185.0, 182.0, 183.0, 183.0, 180.0, 185.0, 180.0, 184.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 76.0, 132.0, 80.0, 56.0, 60.0, 66.0, 95.0, 56.0], "value": 77.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 91.0, 92.0, 70.0, 71.0, 69.0, 97.0, 70.0, 67.0, 103.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 91.0, 88.0, 91.0, 88.0, 84.0, 88.0, 85.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 93.0, 93.0, 99.0, 95.0, 88.0, 149.0], "value": 99.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 107.0, 134.0, 140.0, 84.0, 88.0, 83.0, 84.0], "value": 97.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 56.0, 56.0, 56.0, 56.0, 55.0, 53.0, 55.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 82.0, 78.0, 76.0, 99.0, 69.0, 72.0, 71.0, 70.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 86.0, 88.0, 85.0, 86.0, 88.0, 87.0, 158.0, 108.0], "value": 95.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 151.0, 146.0, 144.0, 139.0, 139.0, 139.0, 136.0, 155.0, 140.0], "value": 143.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 166.0, 165.0, 167.0, 166.0, 164.0, 166.0, 164.0, 168.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 66.0, 66.0, 67.0, 65.0, 66.0, 66.0, 67.0, 66.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1474.6000000000001, "alertThreshold": 2.0}]} 10:40:13 INFO - Return code: 0 10:40:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:40:13 ERROR - # TBPL SUCCESS # 10:40:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:40:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:40:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:40:13 INFO - 2016-06-02 10:40:13.121 screenresolution[1677:9673] starting screenresolution argv=screenresolution get 10:40:13 INFO - 2016-06-02 10:40:13.124 screenresolution[1677:9673] Display 0: 1600x1200x32@60 10:40:13 INFO - 2016-06-02 10:40:13.129 screenresolution[1678:9676] starting screenresolution argv=screenresolution list 10:40:13 INFO - Available Modes on Display 0 10:40:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:40:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:40:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:40:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:40:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:40:13 INFO - Intel Iris: 10:40:13 INFO - Chipset Model: Intel Iris 10:40:13 INFO - Type: GPU 10:40:13 INFO - Bus: Built-In 10:40:13 INFO - VRAM (Dynamic, Max): 1536 MB 10:40:13 INFO - Vendor: Intel (0x8086) 10:40:13 INFO - Device ID: 0x0a2e 10:40:13 INFO - Revision ID: 0x0009 10:40:13 INFO - Displays: 10:40:13 INFO - Display: 10:40:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:40:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:40:13 INFO - Display Serial Number: 1600x1200 60 10:40:13 INFO - Main Display: Yes 10:40:13 INFO - Mirror: Off 10:40:13 INFO - Online: Yes 10:40:13 INFO - Rotation: Supported 10:40:13 INFO - Return code: 0 10:40:13 INFO - Running post-action listener: _resource_record_post_action 10:40:13 INFO - ##### 10:40:13 INFO - ##### Finished run-tests step (success) 10:40:13 INFO - ##### 10:40:13 INFO - Running post-run listener: _resource_record_post_run 10:40:13 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 36570624; Write bytes: 695977472; Read time: 237; Write time: 1931 10:40:13 INFO - TinderboxPrint: CPU usage
53.7% 10:40:13 INFO - TinderboxPrint: I/O read bytes / time
36,570,624 / 237 10:40:13 INFO - TinderboxPrint: I/O write bytes / time
695,977,472 / 1,931 10:40:13 INFO - TinderboxPrint: CPU idle
2,245.3 (46.3%) 10:40:13 INFO - TinderboxPrint: CPU system
962.8 (19.9%) 10:40:13 INFO - TinderboxPrint: CPU user
1,637.6 (33.8%) 10:40:13 INFO - TinderboxPrint: Swap in / out
436,338,688 / 0 10:40:13 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 393609728; Write bytes: 387336192; Read time: 29638; Write time: 813 10:40:13 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 34756096; Write bytes: 293170688; Read time: 222; Write time: 1078 10:40:13 INFO - Running post-run listener: _upload_blobber_files 10:40:13 INFO - Blob upload gear active. 10:40:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:40:13 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 10:40:13 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'] 10:40:13 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 10:40:14 INFO - (blobuploader) - INFO - Open directory for files ... 10:40:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:40:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:40:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:15 INFO - (blobuploader) - INFO - Done attempting. 10:40:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:40:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:40:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:16 INFO - (blobuploader) - INFO - Done attempting. 10:40:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:40:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:40:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:20 INFO - (blobuploader) - INFO - Done attempting. 10:40:20 INFO - (blobuploader) - INFO - Iteration through files over. 10:40:20 INFO - Return code: 0 10:40:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:40:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:40:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e23962ae4b19aacb1103d9da5b002a6c4d8e3ad7d1d5b83820a80ae6409562d9b5ddcf9b5babba575b7135125616fa5b76d89af7df6f1b1c9048ebace77972bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8b8a64e6dcf0710828a33a49a1142c5b51921a21c8e225a59e3f8004e147f0f200941554513dbefb0aafbc92a1199be77ce4b82e9e0bcd79f915bfcd4963e63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1cfde2fc083aa52f3847d8bdb06051814585f3d5629db999dc5be9b2f124dddb0e7bcc8b79917264f38a0cd60cbfbbb04fef5d7136f47fb61ba1a4c9c05c473f"} 10:40:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:40:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:40:20 INFO - Contents: 10:40:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e23962ae4b19aacb1103d9da5b002a6c4d8e3ad7d1d5b83820a80ae6409562d9b5ddcf9b5babba575b7135125616fa5b76d89af7df6f1b1c9048ebace77972bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8b8a64e6dcf0710828a33a49a1142c5b51921a21c8e225a59e3f8004e147f0f200941554513dbefb0aafbc92a1199be77ce4b82e9e0bcd79f915bfcd4963e63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1cfde2fc083aa52f3847d8bdb06051814585f3d5629db999dc5be9b2f124dddb0e7bcc8b79917264f38a0cd60cbfbbb04fef5d7136f47fb61ba1a4c9c05c473f"} 10:40:20 INFO - Running post-run listener: copy_logs_to_upload_dir 10:40:20 INFO - Copying logs to upload dir... 10:40:20 INFO - mkdir: /builds/slave/test/build/upload/logs 10:40:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.987579 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-06-02 10:40:20.147048) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 10:40:20.150935) ========= 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.ukuRctBszD/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.b9zizCWCz5/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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/e23962ae4b19aacb1103d9da5b002a6c4d8e3ad7d1d5b83820a80ae6409562d9b5ddcf9b5babba575b7135125616fa5b76d89af7df6f1b1c9048ebace77972bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8b8a64e6dcf0710828a33a49a1142c5b51921a21c8e225a59e3f8004e147f0f200941554513dbefb0aafbc92a1199be77ce4b82e9e0bcd79f915bfcd4963e63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1cfde2fc083aa52f3847d8bdb06051814585f3d5629db999dc5be9b2f124dddb0e7bcc8b79917264f38a0cd60cbfbbb04fef5d7136f47fb61ba1a4c9c05c473f"} build_url:https://queue.taskcluster.net/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011499 build_url: 'https://queue.taskcluster.net/v1/task/DEgOS8kXRNedxLjUuZ51HA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e23962ae4b19aacb1103d9da5b002a6c4d8e3ad7d1d5b83820a80ae6409562d9b5ddcf9b5babba575b7135125616fa5b76d89af7df6f1b1c9048ebace77972bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8b8a64e6dcf0710828a33a49a1142c5b51921a21c8e225a59e3f8004e147f0f200941554513dbefb0aafbc92a1199be77ce4b82e9e0bcd79f915bfcd4963e63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1cfde2fc083aa52f3847d8bdb06051814585f3d5629db999dc5be9b2f124dddb0e7bcc8b79917264f38a0cd60cbfbbb04fef5d7136f47fb61ba1a4c9c05c473f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 10:40:20.182186) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 10:40:20.182503) ========= 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.ukuRctBszD/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.b9zizCWCz5/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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.005180 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 10:40:20.231340) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 10:40:20.231663) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 10:40:20.231992) ========= ========= Total master_lag: 0.16 =========