builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0082 starttime: 1464227746.62 results: success (0) buildid: 20160525162347 builduid: 649c8b5a91bd4ad58dddfc28c8cd5089 revision: 73817edbdfd6f66e75b13eeb9a883994d04f178d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 18:55:46.616942) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 18:55:46.617375) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 18:55:46.617658) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 18:55:46.639445) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:55:46.639717) ========= 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.TLzO7tl0Vm/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.nME9c2E057/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-05-25 18:55:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.43M=0.001s 2016-05-25 18:55:46 (8.43 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.078200 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:55:46.783730) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:55:46.784068) ========= 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.TLzO7tl0Vm/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.nME9c2E057/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.019270 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:55:46.850753) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-25 18:55:46.851089) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 73817edbdfd6f66e75b13eeb9a883994d04f178d --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 73817edbdfd6f66e75b13eeb9a883994d04f178d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TLzO7tl0Vm/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.nME9c2E057/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-05-25 18:55:46,921 truncating revision to first 12 chars 2016-05-25 18:55:46,921 Setting DEBUG logging. 2016-05-25 18:55:46,921 attempt 1/10 2016-05-25 18:55:46,921 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/73817edbdfd6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 18:55:49,118 unpacking tar archive at: fx-team-73817edbdfd6/testing/mozharness/ program finished with exit code 0 elapsedTime=2.461917 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-25 18:55:49.334301) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 18:55:49.334629) ========= script_repo_revision: 73817edbdfd6f66e75b13eeb9a883994d04f178d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 18:55:49.335011) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 18:55:49.335279) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 18:55:49.352374) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-05-25 18:55:49.352779) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.TLzO7tl0Vm/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.nME9c2E057/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 18:55:49 INFO - MultiFileLogger online at 20160525 18:55:49 in /builds/slave/test 18:55:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:55:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:55:49 INFO - {'append_to_log': False, 18:55:49 INFO - 'base_work_dir': '/builds/slave/test', 18:55:49 INFO - 'blob_upload_branch': 'Fx-Team', 18:55:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:55:49 INFO - 'branch': 'Fx-Team', 18:55:49 INFO - 'buildbot_json_path': 'buildprops.json', 18:55:49 INFO - 'config_files': ('talos/mac_config.py',), 18:55:49 INFO - 'default_actions': ('clobber', 18:55:49 INFO - 'read-buildbot-config', 18:55:49 INFO - 'download-and-extract', 18:55:49 INFO - 'populate-webroot', 18:55:49 INFO - 'create-virtualenv', 18:55:49 INFO - 'install', 18:55:49 INFO - 'run-tests'), 18:55:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:55:49 INFO - 'download_minidump_stackwalk': True, 18:55:49 INFO - 'download_symbols': 'ondemand', 18:55:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:55:49 INFO - 'tooltool.py': '/tools/tooltool.py', 18:55:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:55:49 INFO - '/tools/misc-python/virtualenv.py')}, 18:55:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:55:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:55:49 INFO - 'installer_path': 'installer.exe', 18:55:49 INFO - 'log_level': 'info', 18:55:49 INFO - 'log_name': 'talos', 18:55:49 INFO - 'log_to_console': True, 18:55:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:55:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:55:49 INFO - 'opt_config_files': (), 18:55:49 INFO - 'pip_index': False, 18:55:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:55:49 INFO - 'cmd': ('bash', 18:55:49 INFO - '-c', 18:55:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:55:49 INFO - 'enabled': True, 18:55:49 INFO - 'halt_on_failure': False, 18:55:49 INFO - 'name': 'check_screen_resolution'},), 18:55:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:55:49 INFO - 'cmd': ('bash', 18:55:49 INFO - '-c', 18:55:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:55:49 INFO - 'enabled': True, 18:55:49 INFO - 'halt_on_failure': False, 18:55:49 INFO - 'name': 'check_screen_resolution'},), 18:55:49 INFO - 'run_cmd_checks_enabled': True, 18:55:49 INFO - 'sps_profile': False, 18:55:49 INFO - 'sps_profile_interval': 0, 18:55:49 INFO - 'suite': 'dromaeojs-e10s', 18:55:49 INFO - 'system_bits': '32', 18:55:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:55:49 INFO - 'title': 't-yosemite-r7-0082', 18:55:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:55:49 INFO - 'use_talos_json': True, 18:55:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:55:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:55:49 INFO - 'work_dir': 'build'} 18:55:49 INFO - ##### 18:55:49 INFO - ##### Running clobber step. 18:55:49 INFO - ##### 18:55:49 INFO - Running pre-action listener: _resource_record_pre_action 18:55:49 INFO - Running main action method: clobber 18:55:49 INFO - rmtree: /builds/slave/test/build 18:55:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:55:50 INFO - Running post-action listener: _resource_record_post_action 18:55:50 INFO - ##### 18:55:50 INFO - ##### Finished clobber step (success) 18:55:50 INFO - ##### 18:55:50 INFO - ##### 18:55:50 INFO - ##### Running read-buildbot-config step. 18:55:50 INFO - ##### 18:55:50 INFO - Running pre-action listener: _resource_record_pre_action 18:55:50 INFO - Running main action method: read_buildbot_config 18:55:50 INFO - Using buildbot properties: 18:55:50 INFO - { 18:55:50 INFO - "project": "", 18:55:50 INFO - "product": "firefox", 18:55:50 INFO - "script_repo_revision": "production", 18:55:50 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 18:55:50 INFO - "repository": "", 18:55:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 18:55:50 INFO - "buildid": "20160525162347", 18:55:50 INFO - "pgo_build": "False", 18:55:50 INFO - "basedir": "/builds/slave/test", 18:55:50 INFO - "buildnumber": 19, 18:55:50 INFO - "slavename": "t-yosemite-r7-0082", 18:55:50 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 18:55:50 INFO - "platform": "yosemite_r7", 18:55:50 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 18:55:50 INFO - "branch": "fx-team", 18:55:50 INFO - "repo_path": "integration/fx-team", 18:55:50 INFO - "revision": "73817edbdfd6f66e75b13eeb9a883994d04f178d", 18:55:50 INFO - "stage_platform": "macosx64", 18:55:50 INFO - "builduid": "649c8b5a91bd4ad58dddfc28c8cd5089", 18:55:50 INFO - "slavebuilddir": "test" 18:55:50 INFO - } 18:55:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:55:50 INFO - Running post-action listener: _resource_record_post_action 18:55:50 INFO - ##### 18:55:50 INFO - ##### Finished read-buildbot-config step (success) 18:55:50 INFO - ##### 18:55:50 INFO - ##### 18:55:50 INFO - ##### Running download-and-extract step. 18:55:50 INFO - ##### 18:55:50 INFO - Running pre-action listener: _resource_record_pre_action 18:55:50 INFO - Running main action method: download_and_extract 18:55:50 INFO - mkdir: /builds/slave/test/build/tests 18:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:55:50 INFO - https://queue.taskcluster.net/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:55:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:55:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:55:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/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 18:55:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/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 18:55:52 INFO - Downloaded 1199 bytes. 18:55:52 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:55:52 INFO - Using the following test package requirements: 18:55:52 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:55:52 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:55:52 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:55:52 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:55:52 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:55:52 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:55:52 INFO - u'jsshell-mac.zip'], 18:55:52 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:55:52 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:55:52 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:55:52 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:55:52 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:55:52 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:55:52 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:55:52 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:55:52 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:55:52 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:55:52 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:55:52 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:55:52 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')]} 18:55:52 INFO - https://queue.taskcluster.net/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:55:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:55:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:55:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/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 18:55:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/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 18:55:54 INFO - Downloaded 18622331 bytes. 18:55:54 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'] 18:55:54 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 18:55:57 INFO - Return code: 0 18:55:57 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 18:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:55:57 INFO - https://queue.taskcluster.net/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:55:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:55:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:55:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/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 18:55:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/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 18:55:59 INFO - Downloaded 18622331 bytes. 18:55:59 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'] 18:55:59 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 18:56:03 INFO - Return code: 0 18:56:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:56:03 INFO - https://queue.taskcluster.net/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:56:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:56:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:56:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/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 18:56:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/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 18:56:04 INFO - Downloaded 10834034 bytes. 18:56:04 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'] 18:56:04 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 18:56:04 INFO - Return code: 0 18:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:56:04 INFO - https://queue.taskcluster.net/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:56:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:56:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:56:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:56:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GAbyTb6-R4GBnZPLHUAsPg/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 18:56:13 INFO - Downloaded 110499863 bytes. 18:56:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:56:13 INFO - mkdir: /builds/slave/test/properties 18:56:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:56:13 INFO - Writing to file /builds/slave/test/properties/build_url 18:56:13 INFO - Contents: 18:56:13 INFO - build_url:https://queue.taskcluster.net/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:56:14 INFO - Running post-action listener: _resource_record_post_action 18:56:14 INFO - Running post-action listener: set_extra_try_arguments 18:56:14 INFO - ##### 18:56:14 INFO - ##### Finished download-and-extract step (success) 18:56:14 INFO - ##### 18:56:14 INFO - ##### 18:56:14 INFO - ##### Running populate-webroot step. 18:56:14 INFO - ##### 18:56:14 INFO - Running pre-action listener: _resource_record_pre_action 18:56:14 INFO - Running main action method: populate_webroot 18:56:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:56:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:56:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:56:14 INFO - '--tppagecycles', 18:56:14 INFO - '7'], 18:56:14 INFO - 'tests': ['tsvgm']}}, 18:56:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:56:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:56:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:56:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:56:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:56:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:56:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:14 INFO - 'talos_options': ['--disable-e10s'], 18:56:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:56:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:56:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:14 INFO - 'talos_options': ['--disable-e10s'], 18:56:14 INFO - 'tests': ['damp', 'tps']}, 18:56:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:14 INFO - 'tests': ['damp', 'tps']}, 18:56:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:56:14 INFO - 'tests': ['dromaeo_dom']}, 18:56:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:56:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:56:14 INFO - 'tests': ['a11yr', 18:56:14 INFO - 'ts_paint', 18:56:14 INFO - 'tpaint', 18:56:14 INFO - 'sessionrestore', 18:56:14 INFO - 'sessionrestore_no_auto_restore', 18:56:14 INFO - 'tabpaint']}, 18:56:14 INFO - 'other-e10s': {'tests': ['a11yr', 18:56:14 INFO - 'ts_paint', 18:56:14 INFO - 'tpaint', 18:56:14 INFO - 'sessionrestore', 18:56:14 INFO - 'sessionrestore_no_auto_restore', 18:56:14 INFO - 'tabpaint']}, 18:56:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:56:14 INFO - 'ts_paint', 18:56:14 INFO - 'tpaint', 18:56:14 INFO - 'sessionrestore', 18:56:14 INFO - 'sessionrestore_no_auto_restore', 18:56:14 INFO - 'tabpaint']}, 18:56:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:56:14 INFO - 'ts_paint', 18:56:14 INFO - 'tpaint', 18:56:14 INFO - 'sessionrestore', 18:56:14 INFO - 'sessionrestore_no_auto_restore', 18:56:14 INFO - 'tabpaint']}, 18:56:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:56:14 INFO - 'tests': ['a11yr', 18:56:14 INFO - 'ts_paint', 18:56:14 INFO - 'tpaint', 18:56:14 INFO - 'sessionrestore', 18:56:14 INFO - 'sessionrestore_no_auto_restore', 18:56:14 INFO - 'tabpaint']}, 18:56:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:56:14 INFO - 'tests': ['a11yr', 18:56:14 INFO - 'ts_paint', 18:56:14 INFO - 'tpaint', 18:56:14 INFO - 'sessionrestore', 18:56:14 INFO - 'sessionrestore_no_auto_restore', 18:56:14 INFO - 'tabpaint']}, 18:56:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:56:14 INFO - 'tests': ['tsvgx', 18:56:14 INFO - 'tsvgr_opacity', 18:56:14 INFO - 'tart', 18:56:14 INFO - 'tscrollx', 18:56:14 INFO - 'cart']}, 18:56:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:56:14 INFO - 'tsvgr_opacity', 18:56:14 INFO - 'tart', 18:56:14 INFO - 'tscrollx', 18:56:14 INFO - 'cart']}, 18:56:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:14 INFO - 'talos_options': ['--disable-e10s'], 18:56:14 INFO - 'tests': ['tp5o']}, 18:56:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:14 INFO - 'tests': ['tp5o']}, 18:56:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:14 INFO - 'talos_options': ['--disable-e10s', 18:56:14 INFO - '--xperf_path', 18:56:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:56:14 INFO - 'tests': ['tp5n']}, 18:56:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:56:14 INFO - 'talos_options': ['--xperf_path', 18:56:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:56:14 INFO - 'tests': ['tp5n']}}, 18:56:14 INFO - 'talos.zip': {'path': '', 18:56:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:56:14 INFO - Running post-action listener: _resource_record_post_action 18:56:14 INFO - ##### 18:56:14 INFO - ##### Finished populate-webroot step (success) 18:56:14 INFO - ##### 18:56:14 INFO - ##### 18:56:14 INFO - ##### Running create-virtualenv step. 18:56:14 INFO - ##### 18:56:14 INFO - Running pre-action listener: _resource_record_pre_action 18:56:14 INFO - Running main action method: create_virtualenv 18:56:14 INFO - Creating virtualenv /builds/slave/test/build/venv 18:56:14 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 18:56:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:56:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:56:14 INFO - Using real prefix '/tools/python27' 18:56:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:56:15 INFO - Installing distribute.............................................................................................................................................................................................done. 18:56:17 INFO - Installing pip.................done. 18:56:17 INFO - Return code: 0 18:56:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:56:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:56:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb84adf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc4ad30>, '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 0x10ca6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc41b20>, '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.TLzO7tl0Vm/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.nME9c2E057/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 18:56:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:56:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:56:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TLzO7tl0Vm/Render', 18:56:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:17 INFO - 'HOME': '/Users/cltbld', 18:56:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:17 INFO - 'LOGNAME': 'cltbld', 18:56:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:17 INFO - 'MOZ_NO_REMOTE': '1', 18:56:17 INFO - 'NO_EM_RESTART': '1', 18:56:17 INFO - 'PAGER': '/bin/cat', 18:56:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:17 INFO - 'PWD': '/builds/slave/test', 18:56:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:17 INFO - 'SHELL': '/bin/bash', 18:56:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nME9c2E057/Listeners', 18:56:17 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 18:56:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:17 INFO - 'USER': 'cltbld', 18:56:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:17 INFO - 'XPC_FLAGS': '0x0', 18:56:17 INFO - 'XPC_SERVICE_NAME': '0', 18:56:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:18 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 18:56:18 INFO - Cleaning up... 18:56:18 INFO - Return code: 0 18:56:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:56:18 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')]} 18:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:18 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')]} 18:56:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:18 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 0x10cda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb84adf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc4ad30>, '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 0x10ca6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc41b20>, '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.TLzO7tl0Vm/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.nME9c2E057/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 18:56:18 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 18:56:18 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 18:56:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TLzO7tl0Vm/Render', 18:56:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:18 INFO - 'HOME': '/Users/cltbld', 18:56:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:18 INFO - 'LOGNAME': 'cltbld', 18:56:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:18 INFO - 'MOZ_NO_REMOTE': '1', 18:56:18 INFO - 'NO_EM_RESTART': '1', 18:56:18 INFO - 'PAGER': '/bin/cat', 18:56:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:18 INFO - 'PWD': '/builds/slave/test', 18:56:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:18 INFO - 'SHELL': '/bin/bash', 18:56:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nME9c2E057/Listeners', 18:56:18 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 18:56:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:18 INFO - 'USER': 'cltbld', 18:56:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:18 INFO - 'XPC_FLAGS': '0x0', 18:56:18 INFO - 'XPC_SERVICE_NAME': '0', 18:56:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:18 INFO - Downloading/unpacking psutil>=3.1.1 18:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:56:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:56:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:56:21 INFO - Installing collected packages: psutil 18:56:21 INFO - Running setup.py install for psutil 18:56:21 INFO - building 'psutil._psutil_osx' extension 18:56:21 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 18:56:21 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 18:56:21 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 18:56:22 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 18:56:22 INFO - building 'psutil._psutil_posix' extension 18:56:22 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 18:56:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:56:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:56:22 INFO - ^ 18:56:22 INFO - 1 warning generated. 18:56:22 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 18:56:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:56:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:56:22 INFO - Successfully installed psutil 18:56:22 INFO - Cleaning up... 18:56:22 INFO - Return code: 0 18:56:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:56: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')]} 18:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56: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')]} 18:56:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:22 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 0x10cda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb84adf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc4ad30>, '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 0x10ca6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc41b20>, '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.TLzO7tl0Vm/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.nME9c2E057/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 18:56:22 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 18:56:22 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 18:56:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TLzO7tl0Vm/Render', 18:56:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:22 INFO - 'HOME': '/Users/cltbld', 18:56:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:22 INFO - 'LOGNAME': 'cltbld', 18:56:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:22 INFO - 'MOZ_NO_REMOTE': '1', 18:56:22 INFO - 'NO_EM_RESTART': '1', 18:56:22 INFO - 'PAGER': '/bin/cat', 18:56:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:22 INFO - 'PWD': '/builds/slave/test', 18:56:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:22 INFO - 'SHELL': '/bin/bash', 18:56:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nME9c2E057/Listeners', 18:56:22 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 18:56:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:22 INFO - 'USER': 'cltbld', 18:56:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:22 INFO - 'XPC_FLAGS': '0x0', 18:56:22 INFO - 'XPC_SERVICE_NAME': '0', 18:56:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:56:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:56:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:56:25 INFO - Installing collected packages: mozsystemmonitor 18:56:25 INFO - Running setup.py install for mozsystemmonitor 18:56:25 INFO - Successfully installed mozsystemmonitor 18:56:25 INFO - Cleaning up... 18:56:25 INFO - Return code: 0 18:56:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:56:25 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')]} 18:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:25 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')]} 18:56:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:25 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 0x10cda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb84adf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc4ad30>, '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 0x10ca6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc41b20>, '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.TLzO7tl0Vm/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.nME9c2E057/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 18:56:25 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 18:56:25 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 18:56:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TLzO7tl0Vm/Render', 18:56:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:25 INFO - 'HOME': '/Users/cltbld', 18:56:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:25 INFO - 'LOGNAME': 'cltbld', 18:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:25 INFO - 'MOZ_NO_REMOTE': '1', 18:56:25 INFO - 'NO_EM_RESTART': '1', 18:56:25 INFO - 'PAGER': '/bin/cat', 18:56:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:25 INFO - 'PWD': '/builds/slave/test', 18:56:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:25 INFO - 'SHELL': '/bin/bash', 18:56:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nME9c2E057/Listeners', 18:56:25 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 18:56:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:25 INFO - 'USER': 'cltbld', 18:56:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:25 INFO - 'XPC_FLAGS': '0x0', 18:56:25 INFO - 'XPC_SERVICE_NAME': '0', 18:56:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:25 INFO - Downloading/unpacking blobuploader==1.2.4 18:56:25 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 18:56:25 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 18:56:25 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 18:56:25 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 18:56:25 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 18:56:25 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 18:56:27 INFO - Downloading blobuploader-1.2.4.tar.gz 18:56:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:56:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:56:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:29 INFO - Downloading docopt-0.6.1.tar.gz 18:56:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:56:29 INFO - Installing collected packages: blobuploader, requests, docopt 18:56:29 INFO - Running setup.py install for blobuploader 18:56:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:56:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:56:29 INFO - Running setup.py install for requests 18:56:29 INFO - Running setup.py install for docopt 18:56:30 INFO - Successfully installed blobuploader requests docopt 18:56:30 INFO - Cleaning up... 18:56:30 INFO - Return code: 0 18:56:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56: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')]} 18:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56: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')]} 18:56:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:30 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 0x10cda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb84adf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc4ad30>, '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 0x10ca6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc41b20>, '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.TLzO7tl0Vm/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.nME9c2E057/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 18:56:30 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 18:56:30 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 18:56:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TLzO7tl0Vm/Render', 18:56:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:30 INFO - 'HOME': '/Users/cltbld', 18:56:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:30 INFO - 'LOGNAME': 'cltbld', 18:56:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:30 INFO - 'MOZ_NO_REMOTE': '1', 18:56:30 INFO - 'NO_EM_RESTART': '1', 18:56:30 INFO - 'PAGER': '/bin/cat', 18:56:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:30 INFO - 'PWD': '/builds/slave/test', 18:56:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:30 INFO - 'SHELL': '/bin/bash', 18:56:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nME9c2E057/Listeners', 18:56:30 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 18:56:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:30 INFO - 'USER': 'cltbld', 18:56:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:30 INFO - 'XPC_FLAGS': '0x0', 18:56:30 INFO - 'XPC_SERVICE_NAME': '0', 18:56:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:56:30 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-TDhuhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:56:30 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-3OdGj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:56:30 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-ZD0WwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:56:30 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-CSM9p_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:56:30 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-PL6D_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:56:30 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-1HYMhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:56:31 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-gItG1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:56:31 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-ZnTt2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:56:31 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-xV9YsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:56:31 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-UFcv3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:56:31 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-HJ0KMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:56:31 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-mG7_IC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:56:31 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-cNYQCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:56:31 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-e0HdKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:31 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-eGF9h_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:56:31 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-mAHBnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:56:32 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-vE0H4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:56:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:56:32 INFO - Running setup.py install for manifestparser 18:56:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:56:32 INFO - Running setup.py install for mozcrash 18:56:32 INFO - Running setup.py install for mozdebug 18:56:32 INFO - Running setup.py install for mozdevice 18:56:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:56:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:56:32 INFO - Running setup.py install for mozfile 18:56:32 INFO - Running setup.py install for mozhttpd 18:56:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:56:32 INFO - Running setup.py install for mozinfo 18:56:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:56:33 INFO - Running setup.py install for mozInstall 18:56:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:56:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:56:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:56:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:56:33 INFO - Running setup.py install for mozleak 18:56:33 INFO - Running setup.py install for mozlog 18:56:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:56:33 INFO - Running setup.py install for moznetwork 18:56:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:56:33 INFO - Running setup.py install for mozprocess 18:56:33 INFO - Running setup.py install for mozprofile 18:56:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:56:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:56:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:56:34 INFO - Running setup.py install for mozrunner 18:56:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:56:34 INFO - Running setup.py install for mozscreenshot 18:56:34 INFO - Running setup.py install for moztest 18:56:34 INFO - Running setup.py install for mozversion 18:56:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:56:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:56:34 INFO - Cleaning up... 18:56:34 INFO - Return code: 0 18:56:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:56:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:34 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 0x10cda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb84adf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc4ad30>, '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 0x10ca6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc41b20>, '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.TLzO7tl0Vm/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.nME9c2E057/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 18:56:34 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 18:56:34 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 18:56:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TLzO7tl0Vm/Render', 18:56:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:34 INFO - 'HOME': '/Users/cltbld', 18:56:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:34 INFO - 'LOGNAME': 'cltbld', 18:56:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:34 INFO - 'MOZ_NO_REMOTE': '1', 18:56:34 INFO - 'NO_EM_RESTART': '1', 18:56:34 INFO - 'PAGER': '/bin/cat', 18:56:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:34 INFO - 'PWD': '/builds/slave/test', 18:56:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:34 INFO - 'SHELL': '/bin/bash', 18:56:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nME9c2E057/Listeners', 18:56:34 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 18:56:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:34 INFO - 'USER': 'cltbld', 18:56:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:34 INFO - 'XPC_FLAGS': '0x0', 18:56:34 INFO - 'XPC_SERVICE_NAME': '0', 18:56:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:56:34 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-4bKs_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:56:35 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)) 18:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:56:35 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-dwxV42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:56:35 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)) 18:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:56:35 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-wxydCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:56:35 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)) 18:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:56:35 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-3vymDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:56:35 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)) 18:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:56:35 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-4odHZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:56:35 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)) 18:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:56:35 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-DZefhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:56:35 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)) 18:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:56:35 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-da794G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:56:35 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)) 18:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:56:35 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-up3Eem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:56:35 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)) 18:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:56:35 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-w04_WU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:56:35 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)) 18:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:56:35 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-RyBCz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:56:35 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)) 18:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:56:36 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Bro_oM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:56:36 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)) 18:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:56:36 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-hx8WAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:56:36 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)) 18:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:56:36 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-imQe37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:56:36 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)) 18:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:56:36 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-olH7j2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:56:36 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)) 18:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:36 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-r5qK0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:36 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)) 18:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:56:36 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-MeFxvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:56:36 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)) 18:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:56:36 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-6RMjpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:56:36 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)) 18:56:36 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)) 18:56:36 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)) 18:56:36 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)) 18:56:36 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)) 18:56:36 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)) 18:56:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:56:36 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 18:56:36 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 18:56:36 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 18:56:36 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 18:56:36 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 18:56:36 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 18:56:39 INFO - Downloading blessings-1.6.tar.gz 18:56:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:56:39 INFO - Installing collected packages: blessings 18:56:39 INFO - Running setup.py install for blessings 18:56:39 INFO - Successfully installed blessings 18:56:39 INFO - Cleaning up... 18:56:39 INFO - Return code: 0 18:56:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:56:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:56:39 INFO - Reading from file tmpfile_stdout 18:56:39 INFO - Current package versions: 18:56:39 INFO - blessings == 1.6 18:56:39 INFO - blobuploader == 1.2.4 18:56:39 INFO - docopt == 0.6.1 18:56:39 INFO - manifestparser == 1.1 18:56:39 INFO - mozInstall == 1.12 18:56:39 INFO - mozcrash == 0.17 18:56:39 INFO - mozdebug == 0.1 18:56:39 INFO - mozdevice == 0.48 18:56:39 INFO - mozfile == 1.2 18:56:39 INFO - mozhttpd == 0.7 18:56:39 INFO - mozinfo == 0.9 18:56:39 INFO - mozleak == 0.1 18:56:39 INFO - mozlog == 3.2 18:56:39 INFO - moznetwork == 0.27 18:56:39 INFO - mozprocess == 0.22 18:56:39 INFO - mozprofile == 0.28 18:56:39 INFO - mozrunner == 6.11 18:56:39 INFO - mozscreenshot == 0.1 18:56:39 INFO - mozsystemmonitor == 0.3 18:56:39 INFO - moztest == 0.7 18:56:39 INFO - mozversion == 1.4 18:56:39 INFO - psutil == 3.1.1 18:56:39 INFO - requests == 1.2.3 18:56:39 INFO - wsgiref == 0.1.2 18:56:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:56:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:39 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 0x10cda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb84adf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc4ad30>, '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 0x10ca6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc41b20>, '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.TLzO7tl0Vm/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.nME9c2E057/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 18:56:39 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 18:56:39 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 18:56:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TLzO7tl0Vm/Render', 18:56:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:39 INFO - 'HOME': '/Users/cltbld', 18:56:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:39 INFO - 'LOGNAME': 'cltbld', 18:56:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:39 INFO - 'MOZ_NO_REMOTE': '1', 18:56:39 INFO - 'NO_EM_RESTART': '1', 18:56:39 INFO - 'PAGER': '/bin/cat', 18:56:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:39 INFO - 'PWD': '/builds/slave/test', 18:56:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:39 INFO - 'SHELL': '/bin/bash', 18:56:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nME9c2E057/Listeners', 18:56:39 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 18:56:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:39 INFO - 'USER': 'cltbld', 18:56:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:39 INFO - 'XPC_FLAGS': '0x0', 18:56:39 INFO - 'XPC_SERVICE_NAME': '0', 18:56:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:40 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)) 18:56:40 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)) 18:56:40 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)) 18:56:40 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)) 18:56:40 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)) 18:56:40 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)) 18:56:40 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)) 18:56:40 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)) 18:56:40 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)) 18:56:40 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)) 18:56:40 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)) 18:56:40 INFO - Cleaning up... 18:56:40 INFO - Return code: 0 18:56:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:56:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:56:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:40 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 0x10cda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb84adf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc4ad30>, '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 0x10ca6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc41b20>, '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.TLzO7tl0Vm/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.nME9c2E057/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 18:56:40 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 18:56:40 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 18:56:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TLzO7tl0Vm/Render', 18:56:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:40 INFO - 'HOME': '/Users/cltbld', 18:56:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:40 INFO - 'LOGNAME': 'cltbld', 18:56:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:40 INFO - 'MOZ_NO_REMOTE': '1', 18:56:40 INFO - 'NO_EM_RESTART': '1', 18:56:40 INFO - 'PAGER': '/bin/cat', 18:56:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:40 INFO - 'PWD': '/builds/slave/test', 18:56:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:40 INFO - 'SHELL': '/bin/bash', 18:56:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nME9c2E057/Listeners', 18:56:40 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 18:56:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:40 INFO - 'USER': 'cltbld', 18:56:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:40 INFO - 'XPC_FLAGS': '0x0', 18:56:40 INFO - 'XPC_SERVICE_NAME': '0', 18:56:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:40 INFO - Downloading/unpacking jsonschema 18:56:40 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 18:56:40 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 18:56:40 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 18:56:40 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 18:56:40 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 18:56:40 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 18:56:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:56:42 INFO - Downloading/unpacking functools32 (from jsonschema) 18:56:42 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 18:56:42 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 18:56:42 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 18:56:42 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 18:56:42 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 18:56:42 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 18:56:43 INFO - Downloading functools32-3.2.3-2.tar.gz 18:56:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:56:43 INFO - warning: no files found matching '*.txt' 18:56:43 INFO - no previously-included directories found matching 'build' 18:56:43 INFO - no previously-included directories found matching 'dist' 18:56:43 INFO - no previously-included directories found matching '.git*' 18:56:43 INFO - Installing collected packages: jsonschema, functools32 18:56:43 INFO - Running setup.py install for functools32 18:56:43 INFO - warning: no files found matching '*.txt' 18:56:43 INFO - no previously-included directories found matching 'build' 18:56:43 INFO - no previously-included directories found matching 'dist' 18:56:43 INFO - no previously-included directories found matching '.git*' 18:56:43 INFO - Successfully installed jsonschema functools32 18:56:43 INFO - Cleaning up... 18:56:43 INFO - Return code: 0 18:56:43 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:56: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')]} 18:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:56:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:44 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 0x10cda6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabb84adf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc4ad30>, '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 0x10ca6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc41b20>, '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.TLzO7tl0Vm/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.nME9c2E057/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 18:56:44 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 18:56:44 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 18:56:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TLzO7tl0Vm/Render', 18:56:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:44 INFO - 'HOME': '/Users/cltbld', 18:56:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:44 INFO - 'LOGNAME': 'cltbld', 18:56:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:44 INFO - 'MOZ_NO_REMOTE': '1', 18:56:44 INFO - 'NO_EM_RESTART': '1', 18:56:44 INFO - 'PAGER': '/bin/cat', 18:56:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:44 INFO - 'PWD': '/builds/slave/test', 18:56:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:44 INFO - 'SHELL': '/bin/bash', 18:56:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nME9c2E057/Listeners', 18:56:44 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 18:56:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:44 INFO - 'USER': 'cltbld', 18:56:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:44 INFO - 'XPC_FLAGS': '0x0', 18:56:44 INFO - 'XPC_SERVICE_NAME': '0', 18:56:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:44 INFO - Downloading/unpacking flake8 18:56:44 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 18:56:44 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 18:56:44 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 18:56:44 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 18:56:44 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 18:56:44 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 18:56:47 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:56:47 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:56:47 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 18:56:47 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 18:56:47 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 18:56:47 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 18:56:47 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 18:56:47 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 18:56:47 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:56:47 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:56:47 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 18:56:47 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 18:56:47 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 18:56:47 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 18:56:47 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 18:56:47 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 18:56:48 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:56:48 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 18:56:48 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 18:56:48 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 18:56:48 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 18:56:48 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 18:56:48 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 18:56:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:56:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:56:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:56:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:56:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:56:48 INFO - no previously-included directories found matching 'docs/_build' 18:56:48 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:56:48 INFO - Running setup.py install for pep8 18:56:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:56:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:56:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:56:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:56:48 INFO - no previously-included directories found matching 'docs/_build' 18:56:49 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:56:49 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:56:49 INFO - Cleaning up... 18:56:49 INFO - Return code: 0 18:56:49 INFO - Running post-action listener: _resource_record_post_action 18:56:49 INFO - Running post-action listener: _start_resource_monitoring 18:56:49 INFO - Starting resource monitoring. 18:56:49 INFO - ##### 18:56:49 INFO - ##### Finished create-virtualenv step (success) 18:56:49 INFO - ##### 18:56:49 INFO - ##### 18:56:49 INFO - ##### Running install step. 18:56:49 INFO - ##### 18:56:49 INFO - Running pre-action listener: _resource_record_pre_action 18:56:49 INFO - Running main action method: install 18:56:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:56:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:56:49 INFO - Reading from file tmpfile_stdout 18:56:49 INFO - Detecting whether we're running mozinstall >=1.0... 18:56:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:56:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:56:49 INFO - Reading from file tmpfile_stdout 18:56:49 INFO - Output received: 18:56:49 INFO - Usage: mozinstall [options] installer 18:56:49 INFO - Options: 18:56:49 INFO - -h, --help show this help message and exit 18:56:49 INFO - -d DEST, --destination=DEST 18:56:49 INFO - Directory to install application into. [default: 18:56:49 INFO - "/builds/slave/test"] 18:56:49 INFO - --app=APP Application being installed. [default: firefox] 18:56:49 INFO - mkdir: /builds/slave/test/build/application 18:56:49 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'] 18:56:49 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 18:57:23 INFO - Reading from file tmpfile_stdout 18:57:23 INFO - Output received: 18:57:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:57:23 INFO - Running post-action listener: _resource_record_post_action 18:57:23 INFO - ##### 18:57:23 INFO - ##### Finished install step (success) 18:57:23 INFO - ##### 18:57:23 INFO - ##### 18:57:23 INFO - ##### Running run-tests step. 18:57:23 INFO - ##### 18:57:23 INFO - Running pre-action listener: _resource_record_pre_action 18:57:23 INFO - Running main action method: run_tests 18:57:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:57:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:57:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:57:23 INFO - 2016-05-25 18:57:23.453 screenresolution[1925:14808] starting screenresolution argv=screenresolution get 18:57:23 INFO - 2016-05-25 18:57:23.456 screenresolution[1925:14808] Display 0: 1600x1200x32@60 18:57:23 INFO - 2016-05-25 18:57:23.461 screenresolution[1926:14810] starting screenresolution argv=screenresolution list 18:57:23 INFO - Available Modes on Display 0 18:57:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:57:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:57:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:57:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:57:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:57:23 INFO - Intel Iris: 18:57:23 INFO - Chipset Model: Intel Iris 18:57:23 INFO - Type: GPU 18:57:23 INFO - Bus: Built-In 18:57:23 INFO - VRAM (Dynamic, Max): 1536 MB 18:57:23 INFO - Vendor: Intel (0x8086) 18:57:23 INFO - Device ID: 0x0a2e 18:57:23 INFO - Revision ID: 0x0009 18:57:23 INFO - Displays: 18:57:23 INFO - Display: 18:57:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:57:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:57:23 INFO - Display Serial Number: 1600x1200 60 18:57:23 INFO - Main Display: Yes 18:57:23 INFO - Mirror: Off 18:57:23 INFO - Online: Yes 18:57:23 INFO - Rotation: Supported 18:57:23 INFO - Return code: 0 18:57:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:57:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:57:23 INFO - Python 2.7.3 18:57:23 INFO - Return code: 0 18:57:23 INFO - grabbing minidump binary from tooltool 18:57:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:57:23 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 0x10cc4ad30>, '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 0x10ca6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc41b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:57:23 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 18:57:23 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 18:57:23 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 18:57:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:57:23 INFO - Return code: 0 18:57:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:57:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:57:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:57:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:57:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:57:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:57:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:57:24 INFO - Return code: 0 18:57:24 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:57:24 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0082 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:57:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TLzO7tl0Vm/Render', 18:57:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:57:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:57:24 INFO - 'HOME': '/Users/cltbld', 18:57:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:57:24 INFO - 'LOGNAME': 'cltbld', 18:57:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:57:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:57:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:24 INFO - 'MOZ_NO_REMOTE': '1', 18:57:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:57:24 INFO - 'NO_EM_RESTART': '1', 18:57:24 INFO - 'PAGER': '/bin/cat', 18:57:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:57:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:24 INFO - 'PWD': '/builds/slave/test', 18:57:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:57:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:57:24 INFO - 'SHELL': '/bin/bash', 18:57:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nME9c2E057/Listeners', 18:57:24 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 18:57:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:57:24 INFO - 'USER': 'cltbld', 18:57:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:57:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:57:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:24 INFO - 'XPC_FLAGS': '0x0', 18:57:24 INFO - 'XPC_SERVICE_NAME': '0', 18:57:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:57:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:57:25 INFO - mozversion application_buildid: 20160525162347 18:57:25 INFO - mozversion application_changeset: 73817edbdfd6f66e75b13eeb9a883994d04f178d 18:57:25 INFO - mozversion application_display_name: Nightly 18:57:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:57:25 INFO - mozversion application_name: Firefox 18:57:25 INFO - mozversion application_remotingname: firefox 18:57:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:57:25 INFO - mozversion application_vendor: Mozilla 18:57:25 INFO - mozversion application_version: 49.0a1 18:57:25 INFO - mozversion platform_buildid: 20160525162347 18:57:25 INFO - mozversion platform_changeset: 73817edbdfd6f66e75b13eeb9a883994d04f178d 18:57:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:57:25 INFO - mozversion platform_version: 49.0a1 18:57:25 INFO - using testdate: 1464227845 18:57:25 INFO - actual date: 1464227845 18:57:25 INFO - starting webserver on 'localhost:51421' 18:57:25 INFO - SUITE-START | Running 2 tests 18:57:25 INFO - TEST-START | dromaeo_css 18:57:25 INFO - operating with platform_type : mac_ 18:57:25 INFO - Initialising browser for dromaeo_css test... 18:57:25 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpFu3x2m/profile http://localhost:51421/getInfo.html) 18:57:27 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 18:57:27 INFO - PROCESS | 1944 | colorDepth:24 18:57:27 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 18:57:27 INFO - PROCESS | 1944 | __metrics 18:57:28 ERROR - PROCESS | 1944 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 18:57:28 INFO - TEST-INFO | 1944: exit 0 18:57:28 INFO - Browser initialized. 18:57:28 INFO - Running cycle 1/1 for dromaeo_css test... 18:57:28 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpFu3x2m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:57:40 INFO - PROCESS | 1947 | RSS: Main: 252194816 18:57:40 INFO - PROCESS | 1947 | 18:58:30 INFO - PROCESS | 1947 | 1464227910432 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] 19:00:42 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51421/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51421/tests/dromaeo/cssquery-ext.html) 19:00:43 INFO - PROCESS | 1947 | RSS: Main: 264007680 19:00:43 INFO - PROCESS | 1947 | 19:03:45 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51421/tests/dromaeo/cssquery-ext.html (next: http://localhost:51421/tests/dromaeo/cssquery-jquery.html) 19:03:46 INFO - PROCESS | 1947 | RSS: Main: 275755008 19:03:46 INFO - PROCESS | 1947 | 19:06:48 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51421/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51421/tests/dromaeo/cssquery-mootools.html) 19:06:48 INFO - PROCESS | 1947 | RSS: Main: 277557248 19:06:48 INFO - PROCESS | 1947 | 19:09:50 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51421/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51421/tests/dromaeo/cssquery-prototype.html) 19:09:51 INFO - PROCESS | 1947 | RSS: Main: 279191552 19:09:51 INFO - PROCESS | 1947 | 19:12:53 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51421/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51421/tests/dromaeo/cssquery-yui.html) 19:12:54 INFO - PROCESS | 1947 | RSS: Main: 282144768 19:12:54 INFO - PROCESS | 1947 | 19:15:57 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51421/tests/dromaeo/cssquery-yui.html (next: http://localhost:51421/tests/dromaeo/cssquery-dojo.html) 19:15:57 INFO - PROCESS | 1947 | RSS: Main: 283181056 19:15:57 INFO - PROCESS | 1947 | 19:15:57 INFO - PROCESS | 1947 | __start_tp_report 19:15:57 INFO - PROCESS | 1947 | _x_x_mozilla_page_load 19:15:57 INFO - PROCESS | 1947 | _x_x_mozilla_page_load_details 19:15:57 INFO - PROCESS | 1947 | |i|pagename|runs| 19:15:57 INFO - PROCESS | 1947 | |0;dojo.html;3473.2879759649268;3495.5106285120078;3496.213351995801;3507.105688049547;3537.3632746105723;5720.370759216488;5721.456461636143;5815.15680226368;5843.912341314884;5850.21022162008;5645.943540564593;5781.710914454271;5789.189513468107;5794.768209271635;5852.444017818311;5171.0692075426505;5260.868478288055;5269.156934890419;5302.681839089648;5344.438833922441;4454.33185022246;5255.237990491377;5415.241866138744;5434.456554344573;5459.153831156157;5184.922226166611;5231.633785635007;5265.368155821326;5294.92057619136;5337.813176538822;5294.285271488338;5299.364076310867;5301.814436494704;5313.3092697949405;5315.627906046579;5165.741712914377;5234.790608375661;5245.659032162936;5307.2569840222395;5352.812651557216;5507.504324610804;5524.58565607577;5639.492445679908;5663.8584035399035;5676.8864622707315;6182.814515564495;6242.219722534684;6250.96874515629;6254.624722516618;6265.655388953605;6159.784407545759;6191.721372538246;6266.624002559815;6291.842703932438;6302.810915672491;5020.673656212371;5809.651420914759;5887.61730487517;5898.115282707542;5938.613990090587;5328.440513746078;6343.492520598341;6377.681115944184;6383.1382763326355;6395.776147834804;5158.638895277295;5870.9412905870395;6086.543509236825;6107.664078475637;6207.472364848948;6179.938200618026;6242.250929888442;6245.313015568284;6253.437190652772;6287;6103.84740381494;6138.081874382086;6229.127922090911;6234.9688251559355;6260.843478912972;6132.294322189902;6175.011998080286;6204.844878877974;6261.655608941461;6277.2153480814895;4930.975345123323;4974.0797952378925;4976.278439626234;4980.676256043345;5008.99820035987;4423.778811059434;4453.732776033448;4462.754548499799;4504.954950450519;4513.887152821206;5678.801241956512;5861.736221869941;5872.618279811799;5898.262717160355;5976.61152025117;6213.1922850029205;6233.2208473940755;6254.34329395416;6267.905981410283;6272.153259310026;5936.050231962865;5958.940410595838;5979.940200597938;5989.431004054608;6027.216461860018;5326.201069839555;5388.5419739321815;5416.593755468277;5453.209284653703;5462.562994960394;6468.902966455601;6473.482121430275;6502.057201705727;6505.8048258550425;6521.271862956225;6164.506839452743;6260.311365749672;6271.372862713874;6278.309385967448;6283.905741413974;6016.608920420159;6454.225492940877;6480.8703825922275;6510.73957041713;6559.704813283318;6189.226346706661;6204.937950620617;6238.220222472191;6269.905951410824;6280.277768056732;6107.735039974475;6180.6600636965395;6186.257649082139;6228.190335256388;6253.3121356651645;5924.733386997682;6104.368208815833;6116.914073580276;6158.507319414526;6207;6178.351273116258;6180.845478863064;6186.319504854552;6196.287426945959;6217.316095229611;5879.941200587939;6023.48800351975;6032.276126864805;6052.426369143966;6082.784581359333;6071.5446341523675;6137.984774920692;6154.892119418369;6162.661053641913;6197.225346831646;6071.574989750675;6102.307478454246;6106.27865733419;6106.67263993625;6107.583765481455 19:15:57 INFO - PROCESS | 1947 | |1;ext.html;492.44845772734544;492.9531694489023;504.6921377959444;538.5960529602797;563.3240111865762;37261.44107838385;38732.83801485956;39902.00244993873;40365.394519082234;40418;41127;41966.580334196646;41987.79006104973;42067.789661051655;42102;9481.952590237073;9732.90267097327;9738.977407372231;9740.805183896318;9749.220062394992;8349.206825351583;9594.28042896782;9619.989901060393;9732.367396119265;9743.51282435879;9657.5171241438;9692.66075687351;9751.366161199534;9755.317127801056;9764.804703905918;9650.855237171449;9716.271279653984;9737.123658870665;9742.659006934724;9751.804963900719;9435.339526233167;9538.046195380475;9549.570269337897;9559.187469065138;9564.56959436827;38579.421308680394;39087;39202;39208;39571.406428903305;2334.568104900582;2896.6379202599674;3036.817790932529;3158.873645054195;3185.2514659055105;28191.43617127646;28815.423691526263;29382.85308573465;29704;30400.93596724126;2697.689765676933;2730.962234350934;2736.86315684215;2835.2628316637824;2933.838638874861;495149;500940;505160.47419762664;507395;507868.46065769804;34015.65984340174;35073.473897891556;61121.69439152776;61376.69311653413;61944;33558;34824.47763283553;34860.82569587136;35101;35300;14755.704885902436;15088.24558772057;15117.017393869362;15142.54572362831;15168.393264269527;12345;12631.684207894692;12800.871991280153;12805.167664101804;12926.96584273256;7824.687012519549;7875.684972601031;7919.643616037292;7941.324987376138;7949.7217597383815;2694.94896990174;3308.17295676081;3321.219513414346;3326.2183386904057;3332.000399880075;931.2782593490099;982.7837875667342;1077.4881931082673;1096.9670909872716;1141.5890279499374;23714.644280335808;28798.280042998675;29149.396783176704;29222.85388573086;29830.105096847128;1032.2309879139939;1061.389700921973;1072.1155047086186;1091.650671785037;1113.209621168963;13386.866131338757;14150.221737804313;15151;15170.469033583771;15513.224338783015;518154;640042;648287;658034;660159;59028.704856475444;60288;60620;60796.39203607996;61494.77010459766;1431.1127101197324;1452.8547145285386;1462.3200211901303;1466.6773309871812;1467.5597320803715;28605.427891442472;28650.14049578516;29046.7095329044;29185.708142918298;29526.96655617043;50908.745456273966;51199.23201151911;51664.48335516597;51698.48301516937;51718.48281517137;457571;539735;548513;551171;553935;25973.740262597134;26281;26682.866585666947;26690.065847695238;26761.33096672599;37480;45589.86025349393;47231.763841180575;47700.284495733285;47869.80325491751;37073;38413.80793096017;38422;38988.80505597454;39302.01744956399;36307.27385452329;37208.44187337138;37660.62339376571;37792.43311350387;37993;31848.840755796075;36500;36617.267654647294;36646.63353366539;37103.25793484169;31486.370272593962;32385.676143238266;32477.025689229362;33519.497207542394;33606.831965840014;20852.687209691565;23596.528069438173;24409.023639054238;25106.857368533987;25165 19:15:57 INFO - PROCESS | 1947 | |2;jquery.html;351.7608026541952;373.2162458836442;380.5072431201594;387.6588602030213;394.7947067524887;19498.732582382134;19917.50206244841;20577.588448231025;20891.373258802243;20896.582068358624;20165.395038148843;20609.07259173337;20926.26758063468;21131.894340528313;21259.93700314986;5183.3779946406485;5729.111987641902;5952.315483719362;5954.672493012882;5963.671998040106;5946.137810017545;5991.101334799771;6010.519158467312;6012.3687012863675;6036.0644100164545;5692.8861422771515;5776.104703770901;5874.647521148745;5876.236089308384;5887.264091988502;5535.446455354432;5842.503387212071;5853.356130825606;5862.149988251722;5901.20333754944;5033.119204139261;5061.240813877911;5089.287499750038;5092.261622064817;5097.082525145473;24477;24842.751572484405;25573;25780;25809.870950645316;2100.201923269163;2162.2972533926536;2170.728658917635;2294.105298933417;2310.584094862924;14688.898332624989;15012.024218425877;15028.023178493473;15139.848601513953;15415.460458883885;1658.2537857963964;1676.0949087492738;1713.4773893962335;1757.824217578232;1759.6062918055118;102839.4858025705;107631;108522.45738771335;109179;110737;8778.912210877937;10600.628977985734;11110.388928609003;11243.887561124448;11517.596884109016;13642.386092625855;13655.658608534668;13680.452781888613;13694.383752730952;13710.314484275747;8576.957115214384;8608.139186081467;8754.518501482353;8839;8857;8742.519161446055;8746.34402419821;8757.12428757132;8760;8872.334574906909;6064.848378790565;6206.348333424925;6663.733450661919;6847.931520684829;6857.862842743217;1873.6346412449714;1899.097928483959;1901.6386886491798;1908.7136929460692;1940.427573865713;930.4882314727008;947.3321308477507;968.3608818179968;1014.6905193915926;1048.7692707604315;20186.596268074558;20223;20299.797002030085;20650.07074681643;20818.27136050231;977.6578197630772;991.2020823237219;1005.9899401006043;1035.7100011996654;1104.1001583709412;9378.109079637423;9390.765230869283;9465.574049167804;11120.887911208814;11264.0425563828;446574;479764;503700;567224;568217;111677.44161279305;115417.84582154239;115852.42073789577;115999.42000289944;117049;885.2210055151426;1027.173125633872;1060.1359891688403;1065.2543219746053;1070.9196810239264;17381.304747809947;17918.372856949944;17968.92186468816;18234.36179733703;18328.541786455444;48477.515224848714;50672;53777;54481.72759136179;54538.45461545334;61512;74910.62544687242;76047.61976190083;76160;77293;7837.255460731221;7985;8040.83918321642;8117.756467305991;8167.224113709188;9692.273079518924;12130.090243231616;12321.507139714311;12456.875431245571;12584.811227831407;10444.63443779464;10543.683689489328;10684.25210235276;10782.029617334478;10830.296030757976;8526.232639062515;10782.353058816496;10860.968208020226;10873.673789786319;10876.12990960737;9317.673881414117;9588.945216026372;10504;10777.13782897382;10916.672499825017;7732.729354472566;8082.393820463371;8192.713255036046;8194.590270486333;8211.3430925527;8109.10799812032;10005;10271.383716977005;10442.060214580724;10678.252522323362 19:15:57 INFO - PROCESS | 1947 | |3;mootools.html;1486.3831509923382;1530.441388893054;1699.2183595546048;1817.6001279718466;1864.6270745850816;6483.740650373985;6885.965570172155;7309.524880882739;7324.19433862276;7654.617269136542;7377.668004939777;7477.513961592493;7515.661795219215;7545.509541879774;7573.166951635316;2190.759016508183;2192.1560199323217;2224.5662095891307;2231;2231.966520502194;2178.7276590426195;2200.064972386729;2201.7027701260286;2209.237812954528;2233.084435381494;2193.967090493644;2204.8236141108673;2216.1467834883606;2217.6008318502663;2235.541713948645;1808.629231007647;2186.212963333199;2206.2609025976317;2212.7565967743535;2215.368619943308;2186.5080356919725;2189.3322536626324;2196.1654571262816;2208.6907832903407;2212.524307273944;6443.83890402739;7496.362809161228;7568.281013303727;7593.202713715035;7602.809929751746;4540.659450541222;4566.337881007268;4586.793594288265;4644.651651126179;4683.180443422421;2775.791815613837;3019.6225471816024;3094.3811237752316;3116.890908818203;3135.357251763383;976.6972238606055;995.7908839143788;1000.8698869146964;1005.2259759984844;1019.6380284998793;9380.108889655472;9402.38884472514;9414.388064775767;9427.670031548863;9471.10024547667;4036.8183431745642;4052.2908491014546;4126.216018956448;4160.084781348099;4163.83344666216;8297.875531867026;8314;8314.127016663284;8320.126386729427;8321.833563328699;4613.861584152481;4644.164050470948;4663.743494107789;4678.368420263289;4692.882677933079;3894.824732887027;4173.728707633994;4321.524632290446;4329.480462344476;4336.544662810359;3520.419130843382;3526.612072672004;3562.340966921128;3598.280343931172;3662.08447888028;955.7085089047769;1137.5392965848844;1144.7824913266452;1145.8395824584566;1146.7018575170516;825.7976795685096;886.264400547544;894.3828758156851;917.454114801692;940.4764281863288;5180.067587834226;5208.427073021964;5222.268882356397;5354.223637572531;5356;401.6345125935385;432.1012294427583;434.36102426089667;438.8442103053434;440.3526815581155;3729.1050147964293;3921.705872059607;3931.6264954829817;3985.342418500915;4005.2189822984237;8739.519326437106;8913.777155571162;8921.152490513521;8934.106589341143;8948.910510894937;8428.915710842935;8776.122387761172;8799.912000880036;8914.37599368038;8919.554022298858;7018.157821061505;7035.190953040364;7065.4700897432895;7119.89320160198;7135.250798666273;5279.551238144801;5363.624546281879;5410.78356865721;5461.1808228766;5487.6158668892795;8074.555899425588;8157.06193787722;8309.709160179365;8443.282321002538;8458.492490450593;8357.247847693494;8475.449095808754;8486.70296539643;8511.531865747444;8531.872021919551;6163.876722465436;6466.515011374072;6591.670416479253;6677.399034086956;6713.530052896251;7221.169565500035;7237.384822289953;7271.200167981624;7284.198738138906;7296.124465064227;6438.420542151229;6524.510661700487;6538.869222615616;6614.272430032789;6661.500387471056;6433.935660643334;6454.774082907075;6497.545171837926;6521.9673901630185;6591.110200123114;6115.7247923844425;6200.286966998853;6231.7818876340925;6254.781082662267;6282.183316168869;3351.8826841061423;3516.3846326893204;4118.485189351331;4175.603317684815;4206.957930420656;4563.315502674493;4575.611073058827;4611.400517932647;4622.422197225347;4655.18534256497 19:15:57 INFO - PROCESS | 1947 | |4;prototype.html;300.82251471631736;310.5543545012906;315.23555378207845;317.50310763654863;319.66594908320803;5016.523430274127;5034.899302013958;5328.680279183247;5390.083685773418;5767.942320576793;4809.519048095189;5394.541463975567;5586.022446071942;5654.349749778777;5727.8281651550415;3398.3882901077795;3980.1939632558974;4144.7513149211145;4281.464816897887;4335.504251033404;3240.708336249737;3443.517907492953;4262.358991788153;4335.6748243881675;4363.214621368168;3509.982450087746;4183.581641835823;4210.242156411845;4223.387608796738;4227.429297044906;3401.9149521262043;4200.907763981371;4218.578142185787;4265.594768496988;4279.978600107011;2991.3867657130454;3645.3802853514967;3651;3654.6710796028483;3689.5019172411917;4174.415581818548;4492.68551201416;4953.826616068421;5367.382750983647;5434.619576629638;4657.464391594976;4853.781579828917;4871.342368780205;4885.36490256268;4887.413510378742;5037.143685573461;5291.698111321587;5682.715864206773;5711;5824.563157763185;3344.4481660525985;3967.7898241036473;3977.1846771411792;3992.5807790181902;3993.28120938231;5981.760729570812;6237.87524249517;6240.188775459162;6263.089757623923;6338.778142765027;4033.616806403387;4050.6556942659613;4053.8378464861075;4064.166845796605;4079.2453396121814;3913.7651740895635;4111.362738775495;4134.160298667124;4142.440770495945;4144.357624568136;4835.298881662139;4853.9029219415415;4861.975690121598;4886.462489126193;4909.189983652729;5714.371419143891;5855.214159681338;5948.643081415129;6028.397160284024;6066.211392519032;1299.2330430614356;1368.589423173044;1391.87473127417;1407.6480879780056;1408.6619211389193;701.680735265453;715;747.7121308296237;862.288611895189;873.8208667223204;4186.6715960811225;4197.622214000694;4362.1678894864235;4533.342665313511;4779.354787103766;4539.341795439643;4578.771061446914;5063.139266324733;5147.922781158286;5278.9208161877605;4034.7290603459815;4400.823967041282;4566.675664057452;4667.813287468463;4731.858044258678;2341.8243631727687;2427.3082171581013;2742.931426714308;2747.996981101883;2790.6790719067567;4809.326694262806;4973.626977976666;5320.940459235955;5389.8113566025;5431.619786614899;4740.241561350167;4794.183434288288;4956.8760780980765;5138.203578445347;5411.756470958738;4767.308740232716;4797.472278049412;5290.68255904647;5423.891522169534;5465.6335916021;4313.698041137216;4424;4728.115849331836;4789.353437286061;4950.603951683928;4541.3188021797;4557.430321209848;4859.3682821233015;5075.87310317245;5148.81577237245;4909.558139767438;5439.809606663748;5459.426760190123;5461.863453413696;5469.906018796336;2921.7224363685414;3155.0061730554803;3348.614909285366;3406.2165701888207;3451.723862091077;5113.846584602468;5188.948110518846;5189.299444574934;5259.815906443409;5822.970885145547;4876.853694389174;5951.821445356646;5976.701164941823;5978.073398623221;5985.670788106522;5157.510036546672;5455.345358556999;5669.5180909621495;5920.259967504062;5939.851503712269;4910.680805747615;5507.531859791962;5707.63133299676;5807.041838096836;5821.621594596338;4959.479254678351;5324.281222035131;5710.2862142232225;5737.627054241461;5970.701464926823;5486.561075113901;5746.856328591819;5781.508571771278;5785.4214578541805;5827.475527202402 19:15:57 INFO - PROCESS | 1947 | |5;yui.html;599.9670018149005;631.9825081618593;685.0512040823461;705.3757424679159;705.6260182103483;12437.62687119388;14846.628834279149;15072.246387680614;15147.091174529536;15292.388304467808;11762.705932351695;14106.997356863223;14165.079269847452;14222.715545689105;14305.570832875004;890.3856339125998;908.9774004245224;910.512875611548;911.0206527982446;911.4713466189594;727.9818004549876;877.6577134917387;886.8226354729047;915.555955361649;916.0884919505102;951.8667386565886;964.599691128181;965.6185806606386;967.7822489939778;968.6900191938582;941.1764705882352;955.6511873166279;958.4824194934727;960.0831206198098;960.2653969713175;688.0676683094385;689.9448044156455;711.6299524247362;711.9786406407816;713.2190251674408;14656.633584160374;14749.893757968195;14762.040467369587;14763.630909227248;14774.039687420283;12635.305058221795;12737;12756.2984035878;12829.42267597951;12996.935015324958;9903.752406189831;9940.353876997971;10138.695839124837;10187.439690817002;10332.94833525835;3447.5690538682666;3456.084137703511;3458.56767904012;3524.1189702574356;3539.8938031859084;52989.47010529884;79411.60294198492;79921;80980.59509702472;81807.5909620454;9388.765280867896;9471.10024547667;9474.147326740627;9618.759531011781;10153.746156346002;21792;21960.670589941165;22025.22911698115;22060.448488787588;22084.779152208273;7229;7276.090488688914;7316.890246646304;7377.594232317274;7518.210587888302;3969.5236571611013;4040.3535434330365;4043.595640435933;4044.6359827615656;4049.149678567534;3095.473769459152;3181.8249996249965;3189.0911090339605;3191.978566858583;3193.8243396612943;542.6689719271242;551.2943432406526;551.5394645470997;551.6745120378996;552.3289203617624;540.4514417865869;566.744964765857;569.9829005129852;593.379917985706;598.6168851934765;7645.617719114022;7783.922160778433;7869.252421019994;7870.291673749275;7947.562884041433;558.8407303918364;560.4927540575785;568.2499101186477;577.0472264984904;585.7305639405921;3548.929021419557;3771.981140094282;3798.3163030654746;3858;3917.2557214129224;76048.61975690087;88153;90188;90980;92193;75972.34096806585;76340.23659763443;78286.21713782904;78653.82019269602;80098.59950700209;53861.192082118796;71393.57212855818;71603.64198178975;71610.56778864497;72059.4337594545;5497.835064948058;7309.707611695472;7555.962220189083;7599.848003040019;7684.193159718149;45751;45844.8538786533;46484.30273545967;46497.76751116223;47259;41267.7936610315;53210.73394633002;62993;63247.367526324146;63254.683726581075;8909.797177380971;10228.744281393025;10345.534450949875;10396.16830653561;10730;10668.1998850085;10886.40124793144;10886.965738254854;10956.123510119012;10996.725081873017;8881.911180888108;9587.328886978125;9932.503374831374;10205;10520.631777887736;9601.663941762004;9983.750406239611;10124.898751012395;10190.592376304865;10309.07218350352;10226.693199204035;10232.386056836614;10422.635207767691;10486.580536778443;10495.475226238505;8582.527960962207;8625.8706119407;8645.95677021611;8817.647294108165;8859.202671759573;8310.584470776557;8457.492550446992;8779.692710755093;8971.372003959921;9019.143181397756 19:15:57 INFO - PROCESS | 1947 | __end_tp_report 19:15:57 INFO - PROCESS | 1947 | __start_cc_report 19:15:57 INFO - PROCESS | 1947 | _x_x_mozilla_cycle_collect,419 19:15:57 INFO - PROCESS | 1947 | __end_cc_report 19:15:57 INFO - PROCESS | 1947 | __startTimestamp1464228957383__endTimestamp 19:15:57 INFO - PROCESS | 1947 | 19:15:57 INFO - PROCESS | 1947 | ------- Summary: start ------- 19:15:57 INFO - PROCESS | 1947 | Number of tests: 6 19:15:57 INFO - PROCESS | 1947 | 19:15:57 INFO - PROCESS | 1947 | [#0] dojo.html Cycles:165 Average:5785.74 Median:6032.28 stddev:635.77 (10.5%) stddev-sans-first:611.29 19:15:57 INFO - PROCESS | 1947 | Values: 3473.3 3495.5 3496.2 3507.1 3537.4 5720.4 5721.5 5815.2 5843.9 5850.2 5645.9 5781.7 5789.2 5794.8 5852.4 5171.1 5260.9 5269.2 5302.7 5344.4 4454.3 5255.2 5415.2 5434.5 5459.2 5184.9 5231.6 5265.4 5294.9 5337.8 5294.3 5299.4 5301.8 5313.3 5315.6 5165.7 5234.8 5245.7 5307.3 5352.8 5507.5 5524.6 5639.5 5663.9 5676.9 6182.8 6242.2 6251.0 6254.6 6265.7 6159.8 6191.7 6266.6 6291.8 6302.8 5020.7 5809.7 5887.6 5898.1 5938.6 5328.4 6343.5 6377.7 6383.1 6395.8 5158.6 5870.9 6086.5 6107.7 6207.5 6179.9 6242.3 6245.3 6253.4 6287.0 6103.8 6138.1 6229.1 6235.0 6260.8 6132.3 6175.0 6204.8 6261.7 6277.2 4931.0 4974.1 4976.3 4980.7 5009.0 4423.8 4453.7 4462.8 4505.0 4513.9 5678.8 5861.7 5872.6 5898.3 5976.6 6213.2 6233.2 6254.3 6267.9 6272.2 5936.1 5958.9 5979.9 5989.4 6027.2 5326.2 5388.5 5416.6 5453.2 5462.6 6468.9 6473.5 6502.1 6505.8 6521.3 6164.5 6260.3 6271.4 6278.3 6283.9 6016.6 6454.2 6480.9 6510.7 6559.7 6189.2 6204.9 6238.2 6269.9 6280.3 6107.7 6180.7 6186.3 6228.2 6253.3 5924.7 6104.4 6116.9 6158.5 6207.0 6178.4 6180.8 6186.3 6196.3 6217.3 5879.9 6023.5 6032.3 6052.4 6082.8 6071.5 6138.0 6154.9 6162.7 6197.2 6071.6 6102.3 6106.3 6106.7 6107.6 19:15:57 INFO - PROCESS | 1947 | 19:15:57 INFO - PROCESS | 1947 | [#1] ext.html Cycles:180 Average:67045.95 Median:37726.53 stddev:148990.13 (394.9%) stddev-sans-first:149324.29 19:15:57 INFO - PROCESS | 1947 | Values: 492.4 493.0 504.7 538.6 563.3 37261.4 38732.8 39902.0 40365.4 40418.0 41127.0 41966.6 41987.8 42067.8 42102.0 9482.0 9732.9 9739.0 9740.8 9749.2 8349.2 9594.3 9620.0 9732.4 9743.5 9657.5 9692.7 9751.4 9755.3 9764.8 9650.9 9716.3 9737.1 9742.7 9751.8 9435.3 9538.0 9549.6 9559.2 9564.6 38579.4 39087.0 39202.0 39208.0 39571.4 2334.6 2896.6 3036.8 3158.9 3185.3 28191.4 28815.4 29382.9 29704.0 30400.9 2697.7 2731.0 2736.9 2835.3 2933.8 495149.0 500940.0 505160.5 507395.0 507868.5 34015.7 35073.5 61121.7 61376.7 61944.0 33558.0 34824.5 34860.8 35101.0 35300.0 14755.7 15088.2 15117.0 15142.5 15168.4 12345.0 12631.7 12800.9 12805.2 12927.0 7824.7 7875.7 7919.6 7941.3 7949.7 2694.9 3308.2 3321.2 3326.2 3332.0 931.3 982.8 1077.5 1097.0 1141.6 23714.6 28798.3 29149.4 29222.9 29830.1 1032.2 1061.4 1072.1 1091.7 1113.2 13386.9 14150.2 15151.0 15170.5 15513.2 518154.0 640042.0 648287.0 658034.0 660159.0 59028.7 60288.0 60620.0 60796.4 61494.8 1431.1 1452.9 1462.3 1466.7 1467.6 28605.4 28650.1 29046.7 29185.7 29527.0 50908.7 51199.2 51664.5 51698.5 51718.5 457571.0 539735.0 548513.0 551171.0 553935.0 25973.7 26281.0 26682.9 26690.1 26761.3 37480.0 45589.9 47231.8 47700.3 47869.8 37073.0 38413.8 38422.0 38988.8 39302.0 36307.3 37208.4 37660.6 37792.4 37993.0 31848.8 36500.0 36617.3 36646.6 37103.3 31486.4 32385.7 32477.0 33519.5 33606.8 20852.7 23596.5 24409.0 25106.9 25165.0 19:15:57 INFO - PROCESS | 1947 | 19:15:57 INFO - PROCESS | 1947 | [#2] jquery.html Cycles:180 Average:31857.09 Median:24659.88 stddev:86201.75 (349.6%) stddev-sans-first:86411.11 19:15:57 INFO - PROCESS | 1947 | Values: 351.8 373.2 380.5 387.7 394.8 19498.7 19917.5 20577.6 20891.4 20896.6 20165.4 20609.1 20926.3 21131.9 21259.9 5183.4 5729.1 5952.3 5954.7 5963.7 5946.1 5991.1 6010.5 6012.4 6036.1 5692.9 5776.1 5874.6 5876.2 5887.3 5535.4 5842.5 5853.4 5862.1 5901.2 5033.1 5061.2 5089.3 5092.3 5097.1 24477.0 24842.8 25573.0 25780.0 25809.9 2100.2 2162.3 2170.7 2294.1 2310.6 14688.9 15012.0 15028.0 15139.8 15415.5 1658.3 1676.1 1713.5 1757.8 1759.6 102839.5 107631.0 108522.5 109179.0 110737.0 8778.9 10600.6 11110.4 11243.9 11517.6 13642.4 13655.7 13680.5 13694.4 13710.3 8577.0 8608.1 8754.5 8839.0 8857.0 8742.5 8746.3 8757.1 8760.0 8872.3 6064.8 6206.3 6663.7 6847.9 6857.9 1873.6 1899.1 1901.6 1908.7 1940.4 930.5 947.3 968.4 1014.7 1048.8 20186.6 20223.0 20299.8 20650.1 20818.3 977.7 991.2 1006.0 1035.7 1104.1 9378.1 9390.8 9465.6 11120.9 11264.0 446574.0 479764.0 503700.0 567224.0 568217.0 111677.4 115417.8 115852.4 115999.4 117049.0 885.2 1027.2 1060.1 1065.3 1070.9 17381.3 17918.4 17968.9 18234.4 18328.5 48477.5 50672.0 53777.0 54481.7 54538.5 61512.0 74910.6 76047.6 76160.0 77293.0 7837.3 7985.0 8040.8 8117.8 8167.2 9692.3 12130.1 12321.5 12456.9 12584.8 10444.6 10543.7 10684.3 10782.0 10830.3 8526.2 10782.4 10861.0 10873.7 10876.1 9317.7 9588.9 10504.0 10777.1 10916.7 7732.7 8082.4 8192.7 8194.6 8211.3 8109.1 10005.0 10271.4 10442.1 10678.3 19:15:57 INFO - PROCESS | 1947 | 19:15:57 INFO - PROCESS | 1947 | [#3] mootools.html Cycles:180 Average:4932.31 Median:5316.89 stddev:2628.32 (49.4%) stddev-sans-first:2622.94 19:15:57 INFO - PROCESS | 1947 | Values: 1486.4 1530.4 1699.2 1817.6 1864.6 6483.7 6886.0 7309.5 7324.2 7654.6 7377.7 7477.5 7515.7 7545.5 7573.2 2190.8 2192.2 2224.6 2231.0 2232.0 2178.7 2200.1 2201.7 2209.2 2233.1 2194.0 2204.8 2216.1 2217.6 2235.5 1808.6 2186.2 2206.3 2212.8 2215.4 2186.5 2189.3 2196.2 2208.7 2212.5 6443.8 7496.4 7568.3 7593.2 7602.8 4540.7 4566.3 4586.8 4644.7 4683.2 2775.8 3019.6 3094.4 3116.9 3135.4 976.7 995.8 1000.9 1005.2 1019.6 9380.1 9402.4 9414.4 9427.7 9471.1 4036.8 4052.3 4126.2 4160.1 4163.8 8297.9 8314.0 8314.1 8320.1 8321.8 4613.9 4644.2 4663.7 4678.4 4692.9 3894.8 4173.7 4321.5 4329.5 4336.5 3520.4 3526.6 3562.3 3598.3 3662.1 955.7 1137.5 1144.8 1145.8 1146.7 825.8 886.3 894.4 917.5 940.5 5180.1 5208.4 5222.3 5354.2 5356.0 401.6 432.1 434.4 438.8 440.4 3729.1 3921.7 3931.6 3985.3 4005.2 8739.5 8913.8 8921.2 8934.1 8948.9 8428.9 8776.1 8799.9 8914.4 8919.6 7018.2 7035.2 7065.5 7119.9 7135.3 5279.6 5363.6 5410.8 5461.2 5487.6 8074.6 8157.1 8309.7 8443.3 8458.5 8357.2 8475.4 8486.7 8511.5 8531.9 6163.9 6466.5 6591.7 6677.4 6713.5 7221.2 7237.4 7271.2 7284.2 7296.1 6438.4 6524.5 6538.9 6614.3 6661.5 6433.9 6454.8 6497.5 6522.0 6591.1 6115.7 6200.3 6231.8 6254.8 6282.2 3351.9 3516.4 4118.5 4175.6 4207.0 4563.3 4575.6 4611.4 4622.4 4655.2 19:15:57 INFO - PROCESS | 1947 | 19:15:57 INFO - PROCESS | 1947 | [#4] prototype.html Cycles:180 Average:4456.42 Median:4860.67 stddev:1384.46 (28.5%) stddev-sans-first:1352.75 19:15:57 INFO - PROCESS | 1947 | Values: 300.8 310.6 315.2 317.5 319.7 5016.5 5034.9 5328.7 5390.1 5767.9 4809.5 5394.5 5586.0 5654.3 5727.8 3398.4 3980.2 4144.8 4281.5 4335.5 3240.7 3443.5 4262.4 4335.7 4363.2 3510.0 4183.6 4210.2 4223.4 4227.4 3401.9 4200.9 4218.6 4265.6 4280.0 2991.4 3645.4 3651.0 3654.7 3689.5 4174.4 4492.7 4953.8 5367.4 5434.6 4657.5 4853.8 4871.3 4885.4 4887.4 5037.1 5291.7 5682.7 5711.0 5824.6 3344.4 3967.8 3977.2 3992.6 3993.3 5981.8 6237.9 6240.2 6263.1 6338.8 4033.6 4050.7 4053.8 4064.2 4079.2 3913.8 4111.4 4134.2 4142.4 4144.4 4835.3 4853.9 4862.0 4886.5 4909.2 5714.4 5855.2 5948.6 6028.4 6066.2 1299.2 1368.6 1391.9 1407.6 1408.7 701.7 715.0 747.7 862.3 873.8 4186.7 4197.6 4362.2 4533.3 4779.4 4539.3 4578.8 5063.1 5147.9 5278.9 4034.7 4400.8 4566.7 4667.8 4731.9 2341.8 2427.3 2742.9 2748.0 2790.7 4809.3 4973.6 5320.9 5389.8 5431.6 4740.2 4794.2 4956.9 5138.2 5411.8 4767.3 4797.5 5290.7 5423.9 5465.6 4313.7 4424.0 4728.1 4789.4 4950.6 4541.3 4557.4 4859.4 5075.9 5148.8 4909.6 5439.8 5459.4 5461.9 5469.9 2921.7 3155.0 3348.6 3406.2 3451.7 5113.8 5188.9 5189.3 5259.8 5823.0 4876.9 5951.8 5976.7 5978.1 5985.7 5157.5 5455.3 5669.5 5920.3 5939.9 4910.7 5507.5 5707.6 5807.0 5821.6 4959.5 5324.3 5710.3 5737.6 5970.7 5486.6 5746.9 5781.5 5785.4 5827.5 19:15:57 INFO - PROCESS | 1947 | 19:15:57 INFO - PROCESS | 1947 | [#5] yui.html Cycles:180 Average:17259.76 Median:589.56 stddev:24385.96 (4136.3%) stddev-sans-first:24422.28 19:15:57 INFO - PROCESS | 1947 | Values: 600.0 632.0 685.1 705.4 705.6 12437.6 14846.6 15072.2 15147.1 15292.4 11762.7 14107.0 14165.1 14222.7 14305.6 890.4 909.0 910.5 911.0 911.5 728.0 877.7 886.8 915.6 916.1 951.9 964.6 965.6 967.8 968.7 941.2 955.7 958.5 960.1 960.3 688.1 689.9 711.6 712.0 713.2 14656.6 14749.9 14762.0 14763.6 14774.0 12635.3 12737.0 12756.3 12829.4 12996.9 9903.8 9940.4 10138.7 10187.4 10332.9 3447.6 3456.1 3458.6 3524.1 3539.9 52989.5 79411.6 79921.0 80980.6 81807.6 9388.8 9471.1 9474.1 9618.8 10153.7 21792.0 21960.7 22025.2 22060.4 22084.8 7229.0 7276.1 7316.9 7377.6 7518.2 3969.5 4040.4 4043.6 4044.6 4049.1 3095.5 3181.8 3189.1 3192.0 3193.8 542.7 551.3 551.5 551.7 552.3 540.5 566.7 570.0 593.4 598.6 7645.6 7783.9 7869.3 7870.3 7947.6 558.8 560.5 568.2 577.0 585.7 3548.9 3772.0 3798.3 3858.0 3917.3 76048.6 88153.0 90188.0 90980.0 92193.0 75972.3 76340.2 78286.2 78653.8 80098.6 53861.2 71393.6 71603.6 71610.6 72059.4 5497.8 7309.7 7556.0 7599.8 7684.2 45751.0 45844.9 46484.3 46497.8 47259.0 41267.8 53210.7 62993.0 63247.4 63254.7 8909.8 10228.7 10345.5 10396.2 10730.0 10668.2 10886.4 10887.0 10956.1 10996.7 8881.9 9587.3 9932.5 10205.0 10520.6 9601.7 9983.8 10124.9 10190.6 10309.1 10226.7 10232.4 10422.6 10486.6 10495.5 8582.5 8625.9 8646.0 8817.6 8859.2 8310.6 8457.5 8779.7 8971.4 9019.1 19:15:57 INFO - PROCESS | 1947 | -------- Summary: end -------- 19:15:57 INFO - PROCESS | 1947 | 19:15:57 INFO - TEST-INFO | 1947: exit 0 19:15:57 INFO - TEST-OK | dromaeo_css | took 1112800ms 19:15:57 INFO - TEST-START | kraken 19:15:57 INFO - operating with platform_type : mac_ 19:15:57 INFO - Initialising browser for kraken test... 19:15:57 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpmawBAy/profile http://localhost:51421/getInfo.html) 19:16:00 INFO - PROCESS | 1952 | __metrics Screen width/height:1600/1200 19:16:00 INFO - PROCESS | 1952 | colorDepth:24 19:16:00 INFO - PROCESS | 1952 | Browser inner width/height: 1024/689 19:16:00 INFO - PROCESS | 1952 | __metrics 19:16:01 ERROR - PROCESS | 1952 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 19:16:01 INFO - TEST-INFO | 1952: exit 0 19:16:01 INFO - Browser initialized. 19:16:01 INFO - Running cycle 1/1 for kraken test... 19:16:01 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpmawBAy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:16:13 INFO - PROCESS | 1954 | RSS: Main: 252182528 19:16:13 INFO - PROCESS | 1954 | 19:16:16 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51421/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51421/tests/kraken/driver.html?testName=audio-beat-detection) 19:16:17 INFO - PROCESS | 1954 | RSS: Main: 264245248 19:16:17 INFO - PROCESS | 1954 | 19:16:20 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51421/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51421/tests/kraken/driver.html?testName=audio-dft) 19:16:20 INFO - PROCESS | 1954 | RSS: Main: 269029376 19:16:20 INFO - PROCESS | 1954 | 19:16:24 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51421/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51421/tests/kraken/driver.html?testName=audio-fft) 19:16:25 INFO - PROCESS | 1954 | RSS: Main: 269504512 19:16:25 INFO - PROCESS | 1954 | 19:16:26 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51421/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51421/tests/kraken/driver.html?testName=audio-oscillator) 19:16:27 INFO - PROCESS | 1954 | RSS: Main: 270114816 19:16:27 INFO - PROCESS | 1954 | 19:16:29 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51421/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51421/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:16:30 INFO - PROCESS | 1954 | RSS: Main: 270618624 19:16:30 INFO - PROCESS | 1954 | 19:16:33 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51421/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51421/tests/kraken/driver.html?testName=imaging-darkroom) 19:16:33 INFO - PROCESS | 1954 | RSS: Main: 271208448 19:16:33 INFO - PROCESS | 1954 | 19:16:36 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51421/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51421/tests/kraken/driver.html?testName=imaging-desaturate) 19:16:37 INFO - PROCESS | 1954 | RSS: Main: 271519744 19:16:37 INFO - PROCESS | 1954 | 19:16:40 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51421/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51421/tests/kraken/driver.html?testName=json-parse-financial) 19:16:41 INFO - PROCESS | 1954 | RSS: Main: 271224832 19:16:41 INFO - PROCESS | 1954 | 19:16:42 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51421/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51421/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:16:43 INFO - PROCESS | 1954 | RSS: Main: 270938112 19:16:43 INFO - PROCESS | 1954 | 19:16:45 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51421/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51421/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:16:46 INFO - PROCESS | 1954 | RSS: Main: 271003648 19:16:46 INFO - PROCESS | 1954 | 19:16:48 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51421/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51421/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:16:49 INFO - PROCESS | 1954 | RSS: Main: 271413248 19:16:49 INFO - PROCESS | 1954 | 19:16:51 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51421/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51421/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:16:52 INFO - PROCESS | 1954 | RSS: Main: 271462400 19:16:52 INFO - PROCESS | 1954 | 19:16:55 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51421/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51421/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:16:56 INFO - PROCESS | 1954 | RSS: Main: 271327232 19:16:56 INFO - PROCESS | 1954 | 19:16:57 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51421/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51421/tests/kraken/driver.html?testName=ai-astar) 19:16:58 INFO - PROCESS | 1954 | RSS: Main: 273104896 19:16:58 INFO - PROCESS | 1954 | 19:16:58 INFO - PROCESS | 1954 | __start_tp_report 19:16:58 INFO - PROCESS | 1954 | _x_x_mozilla_page_load 19:16:58 INFO - PROCESS | 1954 | _x_x_mozilla_page_load_details 19:16:58 INFO - PROCESS | 1954 | |i|pagename|runs| 19:16:58 INFO - PROCESS | 1954 | |0;ai-astar;126;159;120;114;116;114;114;116;116;117 19:16:58 INFO - PROCESS | 1954 | |1;audio-beat-detection;157;120;144;166;125;170;137;144;205;148 19:16:58 INFO - PROCESS | 1954 | |2;audio-dft;195;200;219;238;282;248;195;223;272;201 19:16:58 INFO - PROCESS | 1954 | |3;audio-fft;67;102;90;137;58;58;60;60;148;51 19:16:58 INFO - PROCESS | 1954 | |4;audio-oscillator;72;82;124;80;105;110;138;72;72;127 19:16:58 INFO - PROCESS | 1954 | |5;imaging-gaussian-blur;136;128;91;89;90;88;93;89;92;88 19:16:58 INFO - PROCESS | 1954 | |6;imaging-darkroom;152;96;97;96;96;95;96;116;122;96 19:16:58 INFO - PROCESS | 1954 | |7;imaging-desaturate;99;101;133;103;85;75;101;108;128;152 19:16:58 INFO - PROCESS | 1954 | |8;json-parse-financial;60;62;60;59;62;59;59;78;62;58 19:16:58 INFO - PROCESS | 1954 | |9;json-stringify-tinderbox;69;71;74;63;97;116;100;62;106;106 19:16:58 INFO - PROCESS | 1954 | |10;stanford-crypto-aes;86;88;87;85;89;85;81;90;82;78 19:16:58 INFO - PROCESS | 1954 | |11;stanford-crypto-ccm;137;144;164;152;146;153;160;147;185;143 19:16:58 INFO - PROCESS | 1954 | |12;stanford-crypto-pbkdf2;166;169;174;170;166;171;165;218;231;230 19:16:58 INFO - PROCESS | 1954 | |13;stanford-crypto-sha256-iterative;79;73;56;72;97;92;74;95;74;62 19:16:58 INFO - PROCESS | 1954 | __end_tp_report 19:16:58 INFO - PROCESS | 1954 | __start_cc_report 19:16:58 INFO - PROCESS | 1954 | _x_x_mozilla_cycle_collect,1086 19:16:58 INFO - PROCESS | 1954 | __end_cc_report 19:16:58 INFO - PROCESS | 1954 | __startTimestamp1464229018209__endTimestamp 19:16:58 INFO - PROCESS | 1954 | 19:16:58 INFO - PROCESS | 1954 | ------- Summary: start ------- 19:16:58 INFO - PROCESS | 1954 | Number of tests: 14 19:16:58 INFO - PROCESS | 1954 | 19:16:58 INFO - PROCESS | 1954 | [#0] ai-astar Cycles:10 Average:121.20 Median:116.50 stddev:13.77 (11.8%) stddev-sans-first:14.50 19:16:58 INFO - PROCESS | 1954 | Values: 126.0 159.0 120.0 114.0 116.0 114.0 114.0 116.0 116.0 117.0 19:16:58 INFO - PROCESS | 1954 | 19:16:58 INFO - PROCESS | 1954 | [#1] audio-beat-detection Cycles:10 Average:151.60 Median:152.50 stddev:24.66 (16.2%) stddev-sans-first:26.08 19:16:58 INFO - PROCESS | 1954 | Values: 157.0 120.0 144.0 166.0 125.0 170.0 137.0 144.0 205.0 148.0 19:16:58 INFO - PROCESS | 1954 | 19:16:58 INFO - PROCESS | 1954 | [#2] audio-dft Cycles:10 Average:227.30 Median:230.50 stddev:31.87 (13.8%) stddev-sans-first:31.59 19:16:58 INFO - PROCESS | 1954 | Values: 195.0 200.0 219.0 238.0 282.0 248.0 195.0 223.0 272.0 201.0 19:16:58 INFO - PROCESS | 1954 | 19:16:58 INFO - PROCESS | 1954 | [#3] audio-fft Cycles:10 Average:83.10 Median:59.00 stddev:35.18 (59.6%) stddev-sans-first:36.83 19:16:58 INFO - PROCESS | 1954 | Values: 67.0 102.0 90.0 137.0 58.0 58.0 60.0 60.0 148.0 51.0 19:16:58 INFO - PROCESS | 1954 | 19:16:58 INFO - PROCESS | 1954 | [#4] audio-oscillator Cycles:10 Average:98.20 Median:72.00 stddev:25.64 (35.6%) stddev-sans-first:25.38 19:16:58 INFO - PROCESS | 1954 | Values: 72.0 82.0 124.0 80.0 105.0 110.0 138.0 72.0 72.0 127.0 19:16:58 INFO - PROCESS | 1954 | 19:16:58 INFO - PROCESS | 1954 | [#5] imaging-gaussian-blur Cycles:10 Average:98.40 Median:89.50 stddev:17.88 (20.0%) stddev-sans-first:12.78 19:16:58 INFO - PROCESS | 1954 | Values: 136.0 128.0 91.0 89.0 90.0 88.0 93.0 89.0 92.0 88.0 19:16:58 INFO - PROCESS | 1954 | 19:16:58 INFO - PROCESS | 1954 | [#6] imaging-darkroom Cycles:10 Average:106.20 Median:96.00 stddev:18.78 (19.6%) stddev-sans-first:10.26 19:16:58 INFO - PROCESS | 1954 | Values: 152.0 96.0 97.0 96.0 96.0 95.0 96.0 116.0 122.0 96.0 19:16:58 INFO - PROCESS | 1954 | 19:16:58 INFO - PROCESS | 1954 | [#7] imaging-desaturate Cycles:10 Average:108.50 Median:142.50 stddev:23.05 (16.2%) stddev-sans-first:24.19 19:16:58 INFO - PROCESS | 1954 | Values: 99.0 101.0 133.0 103.0 85.0 75.0 101.0 108.0 128.0 152.0 19:16:58 INFO - PROCESS | 1954 | 19:16:58 INFO - PROCESS | 1954 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:61.00 stddev:5.84 (9.6%) stddev-sans-first:6.15 19:16:58 INFO - PROCESS | 1954 | Values: 60.0 62.0 60.0 59.0 62.0 59.0 59.0 78.0 62.0 58.0 19:16:58 INFO - PROCESS | 1954 | 19:16:58 INFO - PROCESS | 1954 | [#9] json-stringify-tinderbox Cycles:10 Average:86.40 Median:66.00 stddev:20.49 (31.0%) stddev-sans-first:20.74 19:16:58 INFO - PROCESS | 1954 | Values: 69.0 71.0 74.0 63.0 97.0 116.0 100.0 62.0 106.0 106.0 19:16:58 INFO - PROCESS | 1954 | 19:16:58 INFO - PROCESS | 1954 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:86.50 stddev:3.78 (4.4%) stddev-sans-first:4.00 19:16:58 INFO - PROCESS | 1954 | Values: 86.0 88.0 87.0 85.0 89.0 85.0 81.0 90.0 82.0 78.0 19:16:58 INFO - PROCESS | 1954 | 19:16:58 INFO - PROCESS | 1954 | [#11] stanford-crypto-ccm Cycles:10 Average:153.10 Median:152.50 stddev:13.81 (9.1%) stddev-sans-first:13.36 19:16:58 INFO - PROCESS | 1954 | Values: 137.0 144.0 164.0 152.0 146.0 153.0 160.0 147.0 185.0 143.0 19:16:58 INFO - PROCESS | 1954 | 19:16:58 INFO - PROCESS | 1954 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.00 Median:172.50 stddev:28.17 (16.3%) stddev-sans-first:28.93 19:16:58 INFO - PROCESS | 1954 | Values: 166.0 169.0 174.0 170.0 166.0 171.0 165.0 218.0 231.0 230.0 19:16:58 INFO - PROCESS | 1954 | 19:16:58 INFO - PROCESS | 1954 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.40 Median:76.50 stddev:13.65 (17.8%) stddev-sans-first:14.46 19:16:58 INFO - PROCESS | 1954 | Values: 79.0 73.0 56.0 72.0 97.0 92.0 74.0 95.0 74.0 62.0 19:16:58 INFO - PROCESS | 1954 | -------- Summary: end -------- 19:16:58 INFO - PROCESS | 1954 | 19:16:59 INFO - TEST-INFO | 1954: exit 0 19:16:59 INFO - TEST-OK | kraken | took 61267ms 19:16:59 INFO - SUITE-END | took 1174s 19:16:59 INFO - Completed test suite (00:19:34) 19:16:59 INFO - javascript benchmark 19:16:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6867.598085914762, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3473.2879759649268, 3495.5106285120078, 3496.213351995801, 3507.105688049547, 3537.3632746105723, 5720.370759216488, 5721.456461636143, 5815.15680226368, 5843.912341314884, 5850.21022162008, 5645.943540564593, 5781.710914454271, 5789.189513468107, 5794.768209271635, 5852.444017818311, 5171.0692075426505, 5260.868478288055, 5269.156934890419, 5302.681839089648, 5344.438833922441, 4454.33185022246, 5255.237990491377, 5415.241866138744, 5434.456554344573, 5459.153831156157, 5184.922226166611, 5231.633785635007, 5265.368155821326, 5294.92057619136, 5337.813176538822, 5294.285271488338, 5299.364076310867, 5301.814436494704, 5313.3092697949405, 5315.627906046579, 5165.741712914377, 5234.790608375661, 5245.659032162936, 5307.2569840222395, 5352.812651557216, 5507.504324610804, 5524.58565607577, 5639.492445679908, 5663.8584035399035, 5676.8864622707315, 6182.814515564495, 6242.219722534684, 6250.96874515629, 6254.624722516618, 6265.655388953605, 6159.784407545759, 6191.721372538246, 6266.624002559815, 6291.842703932438, 6302.810915672491, 5020.673656212371, 5809.651420914759, 5887.61730487517, 5898.115282707542, 5938.613990090587, 5328.440513746078, 6343.492520598341, 6377.681115944184, 6383.1382763326355, 6395.776147834804, 5158.638895277295, 5870.9412905870395, 6086.543509236825, 6107.664078475637, 6207.472364848948, 6179.938200618026, 6242.250929888442, 6245.313015568284, 6253.437190652772, 6287.0, 6103.84740381494, 6138.081874382086, 6229.127922090911, 6234.9688251559355, 6260.843478912972, 6132.294322189902, 6175.011998080286, 6204.844878877974, 6261.655608941461, 6277.2153480814895, 4930.975345123323, 4974.0797952378925, 4976.278439626234, 4980.676256043345, 5008.99820035987, 4423.778811059434, 4453.732776033448, 4462.754548499799, 4504.954950450519, 4513.887152821206, 5678.801241956512, 5861.736221869941, 5872.618279811799, 5898.262717160355, 5976.61152025117, 6213.1922850029205, 6233.2208473940755, 6254.34329395416, 6267.905981410283, 6272.153259310026, 5936.050231962865, 5958.940410595838, 5979.940200597938, 5989.431004054608, 6027.216461860018, 5326.201069839555, 5388.5419739321815, 5416.593755468277, 5453.209284653703, 5462.562994960394, 6468.902966455601, 6473.482121430275, 6502.057201705727, 6505.8048258550425, 6521.271862956225, 6164.506839452743, 6260.311365749672, 6271.372862713874, 6278.309385967448, 6283.905741413974, 6016.608920420159, 6454.225492940877, 6480.8703825922275, 6510.73957041713, 6559.704813283318, 6189.226346706661, 6204.937950620617, 6238.220222472191, 6269.905951410824, 6280.277768056732, 6107.735039974475, 6180.6600636965395, 6186.257649082139, 6228.190335256388, 6253.3121356651645, 5924.733386997682, 6104.368208815833, 6116.914073580276, 6158.507319414526, 6207.0, 6178.351273116258, 6180.845478863064, 6186.319504854552, 6196.287426945959, 6217.316095229611, 5879.941200587939, 6023.48800351975, 6032.276126864805, 6052.426369143966, 6082.784581359333, 6071.5446341523675, 6137.984774920692, 6154.892119418369, 6162.661053641913, 6197.225346831646, 6071.574989750675, 6102.307478454246, 6106.27865733419, 6106.67263993625, 6107.583765481455], "value": 5747.021312360659, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.44845772734544, 492.9531694489023, 504.6921377959444, 538.5960529602797, 563.3240111865762, 37261.44107838385, 38732.83801485956, 39902.00244993873, 40365.394519082234, 40418.0, 41127.0, 41966.580334196646, 41987.79006104973, 42067.789661051655, 42102.0, 9481.952590237073, 9732.90267097327, 9738.977407372231, 9740.805183896318, 9749.220062394992, 8349.206825351583, 9594.28042896782, 9619.989901060393, 9732.367396119265, 9743.51282435879, 9657.5171241438, 9692.66075687351, 9751.366161199534, 9755.317127801056, 9764.804703905918, 9650.855237171449, 9716.271279653984, 9737.123658870665, 9742.659006934724, 9751.804963900719, 9435.339526233167, 9538.046195380475, 9549.570269337897, 9559.187469065138, 9564.56959436827, 38579.421308680394, 39087.0, 39202.0, 39208.0, 39571.406428903305, 2334.568104900582, 2896.6379202599674, 3036.817790932529, 3158.873645054195, 3185.2514659055105, 28191.43617127646, 28815.423691526263, 29382.85308573465, 29704.0, 30400.93596724126, 2697.689765676933, 2730.962234350934, 2736.86315684215, 2835.2628316637824, 2933.838638874861, 495149.0, 500940.0, 505160.47419762664, 507395.0, 507868.46065769804, 34015.65984340174, 35073.473897891556, 61121.69439152776, 61376.69311653413, 61944.0, 33558.0, 34824.47763283553, 34860.82569587136, 35101.0, 35300.0, 14755.704885902436, 15088.24558772057, 15117.017393869362, 15142.54572362831, 15168.393264269527, 12345.0, 12631.684207894692, 12800.871991280153, 12805.167664101804, 12926.96584273256, 7824.687012519549, 7875.684972601031, 7919.643616037292, 7941.324987376138, 7949.7217597383815, 2694.94896990174, 3308.17295676081, 3321.219513414346, 3326.2183386904057, 3332.000399880075, 931.2782593490099, 982.7837875667342, 1077.4881931082673, 1096.9670909872716, 1141.5890279499374, 23714.644280335808, 28798.280042998675, 29149.396783176704, 29222.85388573086, 29830.105096847128, 1032.2309879139939, 1061.389700921973, 1072.1155047086186, 1091.650671785037, 1113.209621168963, 13386.866131338757, 14150.221737804313, 15151.0, 15170.469033583771, 15513.224338783015, 518154.0, 640042.0, 648287.0, 658034.0, 660159.0, 59028.704856475444, 60288.0, 60620.0, 60796.39203607996, 61494.77010459766, 1431.1127101197324, 1452.8547145285386, 1462.3200211901303, 1466.6773309871812, 1467.5597320803715, 28605.427891442472, 28650.14049578516, 29046.7095329044, 29185.708142918298, 29526.96655617043, 50908.745456273966, 51199.23201151911, 51664.48335516597, 51698.48301516937, 51718.48281517137, 457571.0, 539735.0, 548513.0, 551171.0, 553935.0, 25973.740262597134, 26281.0, 26682.866585666947, 26690.065847695238, 26761.33096672599, 37480.0, 45589.86025349393, 47231.763841180575, 47700.284495733285, 47869.80325491751, 37073.0, 38413.80793096017, 38422.0, 38988.80505597454, 39302.01744956399, 36307.27385452329, 37208.44187337138, 37660.62339376571, 37792.43311350387, 37993.0, 31848.840755796075, 36500.0, 36617.267654647294, 36646.63353366539, 37103.25793484169, 31486.370272593962, 32385.676143238266, 32477.025689229362, 33519.497207542394, 33606.831965840014, 20852.687209691565, 23596.528069438173, 24409.023639054238, 25106.857368533987, 25165.0], "value": 18283.943473179977, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.7608026541952, 373.2162458836442, 380.5072431201594, 387.6588602030213, 394.7947067524887, 19498.732582382134, 19917.50206244841, 20577.588448231025, 20891.373258802243, 20896.582068358624, 20165.395038148843, 20609.07259173337, 20926.26758063468, 21131.894340528313, 21259.93700314986, 5183.3779946406485, 5729.111987641902, 5952.315483719362, 5954.672493012882, 5963.671998040106, 5946.137810017545, 5991.101334799771, 6010.519158467312, 6012.3687012863675, 6036.0644100164545, 5692.8861422771515, 5776.104703770901, 5874.647521148745, 5876.236089308384, 5887.264091988502, 5535.446455354432, 5842.503387212071, 5853.356130825606, 5862.149988251722, 5901.20333754944, 5033.119204139261, 5061.240813877911, 5089.287499750038, 5092.261622064817, 5097.082525145473, 24477.0, 24842.751572484405, 25573.0, 25780.0, 25809.870950645316, 2100.201923269163, 2162.2972533926536, 2170.728658917635, 2294.105298933417, 2310.584094862924, 14688.898332624989, 15012.024218425877, 15028.023178493473, 15139.848601513953, 15415.460458883885, 1658.2537857963964, 1676.0949087492738, 1713.4773893962335, 1757.824217578232, 1759.6062918055118, 102839.4858025705, 107631.0, 108522.45738771335, 109179.0, 110737.0, 8778.912210877937, 10600.628977985734, 11110.388928609003, 11243.887561124448, 11517.596884109016, 13642.386092625855, 13655.658608534668, 13680.452781888613, 13694.383752730952, 13710.314484275747, 8576.957115214384, 8608.139186081467, 8754.518501482353, 8839.0, 8857.0, 8742.519161446055, 8746.34402419821, 8757.12428757132, 8760.0, 8872.334574906909, 6064.848378790565, 6206.348333424925, 6663.733450661919, 6847.931520684829, 6857.862842743217, 1873.6346412449714, 1899.097928483959, 1901.6386886491798, 1908.7136929460692, 1940.427573865713, 930.4882314727008, 947.3321308477507, 968.3608818179968, 1014.6905193915926, 1048.7692707604315, 20186.596268074558, 20223.0, 20299.797002030085, 20650.07074681643, 20818.27136050231, 977.6578197630772, 991.2020823237219, 1005.9899401006043, 1035.7100011996654, 1104.1001583709412, 9378.109079637423, 9390.765230869283, 9465.574049167804, 11120.887911208814, 11264.0425563828, 446574.0, 479764.0, 503700.0, 567224.0, 568217.0, 111677.44161279305, 115417.84582154239, 115852.42073789577, 115999.42000289944, 117049.0, 885.2210055151426, 1027.173125633872, 1060.1359891688403, 1065.2543219746053, 1070.9196810239264, 17381.304747809947, 17918.372856949944, 17968.92186468816, 18234.36179733703, 18328.541786455444, 48477.515224848714, 50672.0, 53777.0, 54481.72759136179, 54538.45461545334, 61512.0, 74910.62544687242, 76047.61976190083, 76160.0, 77293.0, 7837.255460731221, 7985.0, 8040.83918321642, 8117.756467305991, 8167.224113709188, 9692.273079518924, 12130.090243231616, 12321.507139714311, 12456.875431245571, 12584.811227831407, 10444.63443779464, 10543.683689489328, 10684.25210235276, 10782.029617334478, 10830.296030757976, 8526.232639062515, 10782.353058816496, 10860.968208020226, 10873.673789786319, 10876.12990960737, 9317.673881414117, 9588.945216026372, 10504.0, 10777.13782897382, 10916.672499825017, 7732.729354472566, 8082.393820463371, 8192.713255036046, 8194.590270486333, 8211.3430925527, 8109.10799812032, 10005.0, 10271.383716977005, 10442.060214580724, 10678.252522323362], "value": 9491.096574470235, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1486.3831509923382, 1530.441388893054, 1699.2183595546048, 1817.6001279718466, 1864.6270745850816, 6483.740650373985, 6885.965570172155, 7309.524880882739, 7324.19433862276, 7654.617269136542, 7377.668004939777, 7477.513961592493, 7515.661795219215, 7545.509541879774, 7573.166951635316, 2190.759016508183, 2192.1560199323217, 2224.5662095891307, 2231.0, 2231.966520502194, 2178.7276590426195, 2200.064972386729, 2201.7027701260286, 2209.237812954528, 2233.084435381494, 2193.967090493644, 2204.8236141108673, 2216.1467834883606, 2217.6008318502663, 2235.541713948645, 1808.629231007647, 2186.212963333199, 2206.2609025976317, 2212.7565967743535, 2215.368619943308, 2186.5080356919725, 2189.3322536626324, 2196.1654571262816, 2208.6907832903407, 2212.524307273944, 6443.83890402739, 7496.362809161228, 7568.281013303727, 7593.202713715035, 7602.809929751746, 4540.659450541222, 4566.337881007268, 4586.793594288265, 4644.651651126179, 4683.180443422421, 2775.791815613837, 3019.6225471816024, 3094.3811237752316, 3116.890908818203, 3135.357251763383, 976.6972238606055, 995.7908839143788, 1000.8698869146964, 1005.2259759984844, 1019.6380284998793, 9380.108889655472, 9402.38884472514, 9414.388064775767, 9427.670031548863, 9471.10024547667, 4036.8183431745642, 4052.2908491014546, 4126.216018956448, 4160.084781348099, 4163.83344666216, 8297.875531867026, 8314.0, 8314.127016663284, 8320.126386729427, 8321.833563328699, 4613.861584152481, 4644.164050470948, 4663.743494107789, 4678.368420263289, 4692.882677933079, 3894.824732887027, 4173.728707633994, 4321.524632290446, 4329.480462344476, 4336.544662810359, 3520.419130843382, 3526.612072672004, 3562.340966921128, 3598.280343931172, 3662.08447888028, 955.7085089047769, 1137.5392965848844, 1144.7824913266452, 1145.8395824584566, 1146.7018575170516, 825.7976795685096, 886.264400547544, 894.3828758156851, 917.454114801692, 940.4764281863288, 5180.067587834226, 5208.427073021964, 5222.268882356397, 5354.223637572531, 5356.0, 401.6345125935385, 432.1012294427583, 434.36102426089667, 438.8442103053434, 440.3526815581155, 3729.1050147964293, 3921.705872059607, 3931.6264954829817, 3985.342418500915, 4005.2189822984237, 8739.519326437106, 8913.777155571162, 8921.152490513521, 8934.106589341143, 8948.910510894937, 8428.915710842935, 8776.122387761172, 8799.912000880036, 8914.37599368038, 8919.554022298858, 7018.157821061505, 7035.190953040364, 7065.4700897432895, 7119.89320160198, 7135.250798666273, 5279.551238144801, 5363.624546281879, 5410.78356865721, 5461.1808228766, 5487.6158668892795, 8074.555899425588, 8157.06193787722, 8309.709160179365, 8443.282321002538, 8458.492490450593, 8357.247847693494, 8475.449095808754, 8486.70296539643, 8511.531865747444, 8531.872021919551, 6163.876722465436, 6466.515011374072, 6591.670416479253, 6677.399034086956, 6713.530052896251, 7221.169565500035, 7237.384822289953, 7271.200167981624, 7284.198738138906, 7296.124465064227, 6438.420542151229, 6524.510661700487, 6538.869222615616, 6614.272430032789, 6661.500387471056, 6433.935660643334, 6454.774082907075, 6497.545171837926, 6521.9673901630185, 6591.110200123114, 6115.7247923844425, 6200.286966998853, 6231.7818876340925, 6254.781082662267, 6282.183316168869, 3351.8826841061423, 3516.3846326893204, 4118.485189351331, 4175.603317684815, 4206.957930420656, 4563.315502674493, 4575.611073058827, 4611.400517932647, 4622.422197225347, 4655.18534256497], "value": 3989.6263189209053, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.82251471631736, 310.5543545012906, 315.23555378207845, 317.50310763654863, 319.66594908320803, 5016.523430274127, 5034.899302013958, 5328.680279183247, 5390.083685773418, 5767.942320576793, 4809.519048095189, 5394.541463975567, 5586.022446071942, 5654.349749778777, 5727.8281651550415, 3398.3882901077795, 3980.1939632558974, 4144.7513149211145, 4281.464816897887, 4335.504251033404, 3240.708336249737, 3443.517907492953, 4262.358991788153, 4335.6748243881675, 4363.214621368168, 3509.982450087746, 4183.581641835823, 4210.242156411845, 4223.387608796738, 4227.429297044906, 3401.9149521262043, 4200.907763981371, 4218.578142185787, 4265.594768496988, 4279.978600107011, 2991.3867657130454, 3645.3802853514967, 3651.0, 3654.6710796028483, 3689.5019172411917, 4174.415581818548, 4492.68551201416, 4953.826616068421, 5367.382750983647, 5434.619576629638, 4657.464391594976, 4853.781579828917, 4871.342368780205, 4885.36490256268, 4887.413510378742, 5037.143685573461, 5291.698111321587, 5682.715864206773, 5711.0, 5824.563157763185, 3344.4481660525985, 3967.7898241036473, 3977.1846771411792, 3992.5807790181902, 3993.28120938231, 5981.760729570812, 6237.87524249517, 6240.188775459162, 6263.089757623923, 6338.778142765027, 4033.616806403387, 4050.6556942659613, 4053.8378464861075, 4064.166845796605, 4079.2453396121814, 3913.7651740895635, 4111.362738775495, 4134.160298667124, 4142.440770495945, 4144.357624568136, 4835.298881662139, 4853.9029219415415, 4861.975690121598, 4886.462489126193, 4909.189983652729, 5714.371419143891, 5855.214159681338, 5948.643081415129, 6028.397160284024, 6066.211392519032, 1299.2330430614356, 1368.589423173044, 1391.87473127417, 1407.6480879780056, 1408.6619211389193, 701.680735265453, 715.0, 747.7121308296237, 862.288611895189, 873.8208667223204, 4186.6715960811225, 4197.622214000694, 4362.1678894864235, 4533.342665313511, 4779.354787103766, 4539.341795439643, 4578.771061446914, 5063.139266324733, 5147.922781158286, 5278.9208161877605, 4034.7290603459815, 4400.823967041282, 4566.675664057452, 4667.813287468463, 4731.858044258678, 2341.8243631727687, 2427.3082171581013, 2742.931426714308, 2747.996981101883, 2790.6790719067567, 4809.326694262806, 4973.626977976666, 5320.940459235955, 5389.8113566025, 5431.619786614899, 4740.241561350167, 4794.183434288288, 4956.8760780980765, 5138.203578445347, 5411.756470958738, 4767.308740232716, 4797.472278049412, 5290.68255904647, 5423.891522169534, 5465.6335916021, 4313.698041137216, 4424.0, 4728.115849331836, 4789.353437286061, 4950.603951683928, 4541.3188021797, 4557.430321209848, 4859.3682821233015, 5075.87310317245, 5148.81577237245, 4909.558139767438, 5439.809606663748, 5459.426760190123, 5461.863453413696, 5469.906018796336, 2921.7224363685414, 3155.0061730554803, 3348.614909285366, 3406.2165701888207, 3451.723862091077, 5113.846584602468, 5188.948110518846, 5189.299444574934, 5259.815906443409, 5822.970885145547, 4876.853694389174, 5951.821445356646, 5976.701164941823, 5978.073398623221, 5985.670788106522, 5157.510036546672, 5455.345358556999, 5669.5180909621495, 5920.259967504062, 5939.851503712269, 4910.680805747615, 5507.531859791962, 5707.63133299676, 5807.041838096836, 5821.621594596338, 4959.479254678351, 5324.281222035131, 5710.2862142232225, 5737.627054241461, 5970.701464926823, 5486.561075113901, 5746.856328591819, 5781.508571771278, 5785.4214578541805, 5827.475527202402], "value": 4014.623752453936, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.9670018149005, 631.9825081618593, 685.0512040823461, 705.3757424679159, 705.6260182103483, 12437.62687119388, 14846.628834279149, 15072.246387680614, 15147.091174529536, 15292.388304467808, 11762.705932351695, 14106.997356863223, 14165.079269847452, 14222.715545689105, 14305.570832875004, 890.3856339125998, 908.9774004245224, 910.512875611548, 911.0206527982446, 911.4713466189594, 727.9818004549876, 877.6577134917387, 886.8226354729047, 915.555955361649, 916.0884919505102, 951.8667386565886, 964.599691128181, 965.6185806606386, 967.7822489939778, 968.6900191938582, 941.1764705882352, 955.6511873166279, 958.4824194934727, 960.0831206198098, 960.2653969713175, 688.0676683094385, 689.9448044156455, 711.6299524247362, 711.9786406407816, 713.2190251674408, 14656.633584160374, 14749.893757968195, 14762.040467369587, 14763.630909227248, 14774.039687420283, 12635.305058221795, 12737.0, 12756.2984035878, 12829.42267597951, 12996.935015324958, 9903.752406189831, 9940.353876997971, 10138.695839124837, 10187.439690817002, 10332.94833525835, 3447.5690538682666, 3456.084137703511, 3458.56767904012, 3524.1189702574356, 3539.8938031859084, 52989.47010529884, 79411.60294198492, 79921.0, 80980.59509702472, 81807.5909620454, 9388.765280867896, 9471.10024547667, 9474.147326740627, 9618.759531011781, 10153.746156346002, 21792.0, 21960.670589941165, 22025.22911698115, 22060.448488787588, 22084.779152208273, 7229.0, 7276.090488688914, 7316.890246646304, 7377.594232317274, 7518.210587888302, 3969.5236571611013, 4040.3535434330365, 4043.595640435933, 4044.6359827615656, 4049.149678567534, 3095.473769459152, 3181.8249996249965, 3189.0911090339605, 3191.978566858583, 3193.8243396612943, 542.6689719271242, 551.2943432406526, 551.5394645470997, 551.6745120378996, 552.3289203617624, 540.4514417865869, 566.744964765857, 569.9829005129852, 593.379917985706, 598.6168851934765, 7645.617719114022, 7783.922160778433, 7869.252421019994, 7870.291673749275, 7947.562884041433, 558.8407303918364, 560.4927540575785, 568.2499101186477, 577.0472264984904, 585.7305639405921, 3548.929021419557, 3771.981140094282, 3798.3163030654746, 3858.0, 3917.2557214129224, 76048.61975690087, 88153.0, 90188.0, 90980.0, 92193.0, 75972.34096806585, 76340.23659763443, 78286.21713782904, 78653.82019269602, 80098.59950700209, 53861.192082118796, 71393.57212855818, 71603.64198178975, 71610.56778864497, 72059.4337594545, 5497.835064948058, 7309.707611695472, 7555.962220189083, 7599.848003040019, 7684.193159718149, 45751.0, 45844.8538786533, 46484.30273545967, 46497.76751116223, 47259.0, 41267.7936610315, 53210.73394633002, 62993.0, 63247.367526324146, 63254.683726581075, 8909.797177380971, 10228.744281393025, 10345.534450949875, 10396.16830653561, 10730.0, 10668.1998850085, 10886.40124793144, 10886.965738254854, 10956.123510119012, 10996.725081873017, 8881.911180888108, 9587.328886978125, 9932.503374831374, 10205.0, 10520.631777887736, 9601.663941762004, 9983.750406239611, 10124.898751012395, 10190.592376304865, 10309.07218350352, 10226.693199204035, 10232.386056836614, 10422.635207767691, 10486.580536778443, 10495.475226238505, 8582.527960962207, 8625.8706119407, 8645.95677021611, 8817.647294108165, 8859.202671759573, 8310.584470776557, 8457.492550446992, 8779.692710755093, 8971.372003959921, 9019.143181397756], "value": 6567.109887549313, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1644.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 159.0, 120.0, 114.0, 116.0, 114.0, 114.0, 116.0, 116.0, 117.0], "value": 121.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 120.0, 144.0, 166.0, 125.0, 170.0, 137.0, 144.0, 205.0, 148.0], "value": 151.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 200.0, 219.0, 238.0, 282.0, 248.0, 195.0, 223.0, 272.0, 201.0], "value": 227.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 102.0, 90.0, 137.0, 58.0, 58.0, 60.0, 60.0, 148.0, 51.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 82.0, 124.0, 80.0, 105.0, 110.0, 138.0, 72.0, 72.0, 127.0], "value": 98.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 128.0, 91.0, 89.0, 90.0, 88.0, 93.0, 89.0, 92.0, 88.0], "value": 98.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 96.0, 97.0, 96.0, 96.0, 95.0, 96.0, 116.0, 122.0, 96.0], "value": 106.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 101.0, 133.0, 103.0, 85.0, 75.0, 101.0, 108.0, 128.0, 152.0], "value": 108.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 60.0, 59.0, 62.0, 59.0, 59.0, 78.0, 62.0, 58.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 74.0, 63.0, 97.0, 116.0, 100.0, 62.0, 106.0, 106.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 87.0, 85.0, 89.0, 85.0, 81.0, 90.0, 82.0, 78.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 144.0, 164.0, 152.0, 146.0, 153.0, 160.0, 147.0, 185.0, 143.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 169.0, 174.0, 170.0, 166.0, 171.0, 165.0, 218.0, 231.0, 230.0], "value": 186.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 56.0, 72.0, 97.0, 92.0, 74.0, 95.0, 74.0, 62.0], "value": 77.4, "unit": "score"}]}]} 19:16:59 INFO - Return code: 0 19:16:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:16:59 ERROR - # TBPL SUCCESS # 19:16:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:16:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:16:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:16:59 INFO - 2016-05-25 19:16:59.546 screenresolution[1959:17371] starting screenresolution argv=screenresolution get 19:16:59 INFO - 2016-05-25 19:16:59.550 screenresolution[1959:17371] Display 0: 1600x1200x32@60 19:16:59 INFO - 2016-05-25 19:16:59.556 screenresolution[1960:17374] starting screenresolution argv=screenresolution list 19:16:59 INFO - Available Modes on Display 0 19:16:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:16:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:16:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:16:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:16:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:16:59 INFO - Intel Iris: 19:16:59 INFO - Chipset Model: Intel Iris 19:16:59 INFO - Type: GPU 19:16:59 INFO - Bus: Built-In 19:16:59 INFO - VRAM (Dynamic, Max): 1536 MB 19:16:59 INFO - Vendor: Intel (0x8086) 19:16:59 INFO - Device ID: 0x0a2e 19:16:59 INFO - Revision ID: 0x0009 19:16:59 INFO - Displays: 19:16:59 INFO - Display: 19:16:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:16:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:16:59 INFO - Display Serial Number: 1600x1200 60 19:16:59 INFO - Main Display: Yes 19:16:59 INFO - Mirror: Off 19:16:59 INFO - Online: Yes 19:16:59 INFO - Rotation: Supported 19:16:59 INFO - Return code: 0 19:16:59 INFO - Running post-action listener: _resource_record_post_action 19:16:59 INFO - ##### 19:16:59 INFO - ##### Finished run-tests step (success) 19:16:59 INFO - ##### 19:16:59 INFO - Running post-run listener: _resource_record_post_run 19:16:59 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 409600; Write bytes: 698010112; Read time: 6; Write time: 2063 19:16:59 INFO - TinderboxPrint: CPU usage
54.5% 19:16:59 INFO - TinderboxPrint: I/O read bytes / time
409,600 / 6 19:16:59 INFO - TinderboxPrint: I/O write bytes / time
698,010,112 / 2,063 19:16:59 INFO - TinderboxPrint: CPU idle
2,201.2 (45.5%) 19:16:59 INFO - TinderboxPrint: CPU system
973.2 (20.1%) 19:16:59 INFO - TinderboxPrint: CPU user
1,667.4 (34.4%) 19:16:59 INFO - TinderboxPrint: Swap in / out
397,107,200 / 0 19:16:59 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 395178496; Write bytes: 390552576; Read time: 29787; Write time: 738 19:16:59 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 409600; Write bytes: 296349184; Read time: 6; Write time: 1293 19:17:00 INFO - Running post-run listener: _upload_blobber_files 19:17:00 INFO - Blob upload gear active. 19:17:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:17:00 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 19:17:00 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'] 19:17:00 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 19:17:00 INFO - (blobuploader) - INFO - Open directory for files ... 19:17:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:17:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:17:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:01 INFO - (blobuploader) - INFO - Done attempting. 19:17:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:17:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:17:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:02 INFO - (blobuploader) - INFO - Done attempting. 19:17:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:17:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:17:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:05 INFO - (blobuploader) - INFO - Done attempting. 19:17:05 INFO - (blobuploader) - INFO - Iteration through files over. 19:17:05 INFO - Return code: 0 19:17:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:17:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:17:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f746283d8d4dd5176125383a7a429bc42451a868326428874c4602bc4cb40d4bf072b2b768517b69cf290553e5f1774c69a4a3e22eea3a4e740a098f9aa981df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65c56efacbb6f334538ecd0614e4afece68e1a2e102ea26930c6acdcffa2d5812c3cc8a430735bd5e936575240b93b15b83c720e9e3e2090d74cd9d08aba90bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1064909180d7c4e4e67e1698cb859e6cc1d9e2eeb66891b27b383ced3d4cf0cee37d89c97662a98574c2a2ae56be8c4b4ba9f8778f95b3252ff7912f9f9ae8d"} 19:17:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:17:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:17:05 INFO - Contents: 19:17:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f746283d8d4dd5176125383a7a429bc42451a868326428874c4602bc4cb40d4bf072b2b768517b69cf290553e5f1774c69a4a3e22eea3a4e740a098f9aa981df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65c56efacbb6f334538ecd0614e4afece68e1a2e102ea26930c6acdcffa2d5812c3cc8a430735bd5e936575240b93b15b83c720e9e3e2090d74cd9d08aba90bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1064909180d7c4e4e67e1698cb859e6cc1d9e2eeb66891b27b383ced3d4cf0cee37d89c97662a98574c2a2ae56be8c4b4ba9f8778f95b3252ff7912f9f9ae8d"} 19:17:05 INFO - Running post-run listener: copy_logs_to_upload_dir 19:17:05 INFO - Copying logs to upload dir... 19:17:05 INFO - mkdir: /builds/slave/test/build/upload/logs 19:17:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.029469 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-05-25 19:17:05.418356) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 19:17:05.422343) ========= 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.TLzO7tl0Vm/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.nME9c2E057/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f746283d8d4dd5176125383a7a429bc42451a868326428874c4602bc4cb40d4bf072b2b768517b69cf290553e5f1774c69a4a3e22eea3a4e740a098f9aa981df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65c56efacbb6f334538ecd0614e4afece68e1a2e102ea26930c6acdcffa2d5812c3cc8a430735bd5e936575240b93b15b83c720e9e3e2090d74cd9d08aba90bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1064909180d7c4e4e67e1698cb859e6cc1d9e2eeb66891b27b383ced3d4cf0cee37d89c97662a98574c2a2ae56be8c4b4ba9f8778f95b3252ff7912f9f9ae8d"} build_url:https://queue.taskcluster.net/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011145 build_url: 'https://queue.taskcluster.net/v1/task/GAbyTb6-R4GBnZPLHUAsPg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f746283d8d4dd5176125383a7a429bc42451a868326428874c4602bc4cb40d4bf072b2b768517b69cf290553e5f1774c69a4a3e22eea3a4e740a098f9aa981df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65c56efacbb6f334538ecd0614e4afece68e1a2e102ea26930c6acdcffa2d5812c3cc8a430735bd5e936575240b93b15b83c720e9e3e2090d74cd9d08aba90bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1064909180d7c4e4e67e1698cb859e6cc1d9e2eeb66891b27b383ced3d4cf0cee37d89c97662a98574c2a2ae56be8c4b4ba9f8778f95b3252ff7912f9f9ae8d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 19:17:05.453355) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 19:17:05.453668) ========= 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.TLzO7tl0Vm/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.nME9c2E057/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.005257 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 19:17:05.502745) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 19:17:05.503066) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 19:17:05.503404) ========= ========= Total master_lag: 0.23 =========