builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0389 starttime: 1464953360.14 results: success (0) buildid: 20160603022511 builduid: 2b2a503372d548b1a8fe18a22bf344a3 revision: 10371d73bb08fa730e311885433bd147bf2a3576 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 04:29:20.145000) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 04:29:20.145465) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 04:29:20.145774) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 04:29:20.873491) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:29:20.874265) ========= 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.YAmW3xkmSd/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.4TVUoHcw7R/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-03 04:29:20-- 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% 19.5M=0.001s 2016-06-03 04:29:21 (19.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.475572 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:29:21.372732) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:29:21.373196) ========= 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.YAmW3xkmSd/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.4TVUoHcw7R/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.025832 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:29:21.424896) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-03 04:29:21.425716) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 10371d73bb08fa730e311885433bd147bf2a3576 --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 10371d73bb08fa730e311885433bd147bf2a3576 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YAmW3xkmSd/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.4TVUoHcw7R/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-03 04:29:21,512 truncating revision to first 12 chars 2016-06-03 04:29:21,512 Setting DEBUG logging. 2016-06-03 04:29:21,512 attempt 1/10 2016-06-03 04:29:21,512 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/10371d73bb08?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-03 04:29:22,277 unpacking tar archive at: fx-team-10371d73bb08/testing/mozharness/ program finished with exit code 0 elapsedTime=1.031731 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-03 04:29:22.552959) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 04:29:22.553329) ========= script_repo_revision: 10371d73bb08fa730e311885433bd147bf2a3576 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 04:29:22.553801) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 04:29:22.554121) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 04:29:22.571944) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-06-03 04:29:22.572260) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YAmW3xkmSd/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.4TVUoHcw7R/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:29:22 INFO - MultiFileLogger online at 20160603 04:29:22 in /builds/slave/test 04:29:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 04:29:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:29:22 INFO - {'append_to_log': False, 04:29:22 INFO - 'base_work_dir': '/builds/slave/test', 04:29:22 INFO - 'blob_upload_branch': 'Fx-Team', 04:29:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:29:22 INFO - 'branch': 'Fx-Team', 04:29:22 INFO - 'buildbot_json_path': 'buildprops.json', 04:29:22 INFO - 'config_files': ('talos/mac_config.py',), 04:29:22 INFO - 'default_actions': ('clobber', 04:29:22 INFO - 'read-buildbot-config', 04:29:22 INFO - 'download-and-extract', 04:29:22 INFO - 'populate-webroot', 04:29:22 INFO - 'create-virtualenv', 04:29:22 INFO - 'install', 04:29:22 INFO - 'run-tests'), 04:29:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:29:22 INFO - 'download_minidump_stackwalk': True, 04:29:22 INFO - 'download_symbols': 'ondemand', 04:29:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:29:22 INFO - 'tooltool.py': '/tools/tooltool.py', 04:29:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:29:22 INFO - '/tools/misc-python/virtualenv.py')}, 04:29:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:29:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:29:22 INFO - 'installer_path': 'installer.exe', 04:29:22 INFO - 'log_level': 'info', 04:29:22 INFO - 'log_name': 'talos', 04:29:22 INFO - 'log_to_console': True, 04:29:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:29:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:29:22 INFO - 'opt_config_files': (), 04:29:22 INFO - 'pip_index': False, 04:29:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:29:22 INFO - 'cmd': ('bash', 04:29:22 INFO - '-c', 04:29:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:29:22 INFO - 'enabled': True, 04:29:22 INFO - 'halt_on_failure': False, 04:29:22 INFO - 'name': 'check_screen_resolution'},), 04:29:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:29:22 INFO - 'cmd': ('bash', 04:29:22 INFO - '-c', 04:29:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:29:22 INFO - 'enabled': True, 04:29:22 INFO - 'halt_on_failure': False, 04:29:22 INFO - 'name': 'check_screen_resolution'},), 04:29:22 INFO - 'run_cmd_checks_enabled': True, 04:29:22 INFO - 'sps_profile': False, 04:29:22 INFO - 'sps_profile_interval': 0, 04:29:22 INFO - 'suite': 'dromaeojs', 04:29:22 INFO - 'system_bits': '32', 04:29:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:29:22 INFO - 'title': 't-yosemite-r7-0389', 04:29:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:29:22 INFO - 'use_talos_json': True, 04:29:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:29:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:29:22 INFO - 'work_dir': 'build'} 04:29:22 INFO - ##### 04:29:22 INFO - ##### Running clobber step. 04:29:22 INFO - ##### 04:29:22 INFO - Running pre-action listener: _resource_record_pre_action 04:29:22 INFO - Running main action method: clobber 04:29:22 INFO - rmtree: /builds/slave/test/build 04:29:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:29:24 INFO - Running post-action listener: _resource_record_post_action 04:29:24 INFO - ##### 04:29:24 INFO - ##### Finished clobber step (success) 04:29:24 INFO - ##### 04:29:24 INFO - ##### 04:29:24 INFO - ##### Running read-buildbot-config step. 04:29:24 INFO - ##### 04:29:24 INFO - Running pre-action listener: _resource_record_pre_action 04:29:24 INFO - Running main action method: read_buildbot_config 04:29:24 INFO - Using buildbot properties: 04:29:24 INFO - { 04:29:24 INFO - "project": "", 04:29:24 INFO - "product": "firefox", 04:29:24 INFO - "script_repo_revision": "production", 04:29:24 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:29:24 INFO - "repository": "", 04:29:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:29:24 INFO - "buildid": "20160603022511", 04:29:24 INFO - "pgo_build": "False", 04:29:24 INFO - "basedir": "/builds/slave/test", 04:29:24 INFO - "buildnumber": 48, 04:29:24 INFO - "slavename": "t-yosemite-r7-0389", 04:29:24 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 04:29:24 INFO - "platform": "yosemite_r7", 04:29:24 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:29:24 INFO - "branch": "fx-team", 04:29:24 INFO - "repo_path": "integration/fx-team", 04:29:24 INFO - "revision": "10371d73bb08fa730e311885433bd147bf2a3576", 04:29:24 INFO - "stage_platform": "macosx64", 04:29:24 INFO - "builduid": "2b2a503372d548b1a8fe18a22bf344a3", 04:29:24 INFO - "slavebuilddir": "test" 04:29:24 INFO - } 04:29:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:29:24 INFO - Running post-action listener: _resource_record_post_action 04:29:24 INFO - ##### 04:29:24 INFO - ##### Finished read-buildbot-config step (success) 04:29:24 INFO - ##### 04:29:24 INFO - ##### 04:29:24 INFO - ##### Running download-and-extract step. 04:29:24 INFO - ##### 04:29:24 INFO - Running pre-action listener: _resource_record_pre_action 04:29:24 INFO - Running main action method: download_and_extract 04:29:24 INFO - mkdir: /builds/slave/test/build/tests 04:29:24 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')]} 04:29:24 INFO - https://queue.taskcluster.net/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:29:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:29:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:29:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/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 04:29:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/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 04:29:27 INFO - Downloaded 1199 bytes. 04:29:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:29:27 INFO - Using the following test package requirements: 04:29:27 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:29:27 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:29:27 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:29:27 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:29:27 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:29:27 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:29:27 INFO - u'jsshell-mac.zip'], 04:29:27 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:29:27 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:29:27 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:29:27 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:29:27 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:29:27 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:29:27 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:29:27 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:29:27 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:29:27 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:29:27 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:29:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:27 INFO - https://queue.taskcluster.net/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:29:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:29:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:29:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/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 04:29:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/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 04:29:29 INFO - Downloaded 18713698 bytes. 04:29:29 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'] 04:29:29 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 04:29:32 INFO - Return code: 0 04:29:32 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 04:29:32 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')]} 04:29:32 INFO - https://queue.taskcluster.net/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:29:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:29:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:29:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/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 04:29:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/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 04:29:33 INFO - Downloaded 18713698 bytes. 04:29:33 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'] 04:29:33 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 04:29:36 INFO - Return code: 0 04:29:36 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')]} 04:29:36 INFO - https://queue.taskcluster.net/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:29:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:29:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:29:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/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 04:29:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/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 04:29:37 INFO - Downloaded 10834055 bytes. 04:29:37 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'] 04:29:37 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 04:29:38 INFO - Return code: 0 04:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:38 INFO - https://queue.taskcluster.net/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:29:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:29:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:29:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:29:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/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 04:29:44 INFO - Downloaded 111082277 bytes. 04:29:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:29:44 INFO - mkdir: /builds/slave/test/properties 04:29:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:29:44 INFO - Writing to file /builds/slave/test/properties/build_url 04:29:44 INFO - Contents: 04:29:44 INFO - build_url:https://queue.taskcluster.net/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:29:45 INFO - Running post-action listener: _resource_record_post_action 04:29:45 INFO - Running post-action listener: set_extra_try_arguments 04:29:45 INFO - ##### 04:29:45 INFO - ##### Finished download-and-extract step (success) 04:29:45 INFO - ##### 04:29:45 INFO - ##### 04:29:45 INFO - ##### Running populate-webroot step. 04:29:45 INFO - ##### 04:29:45 INFO - Running pre-action listener: _resource_record_pre_action 04:29:45 INFO - Running main action method: populate_webroot 04:29:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:29:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:29:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:29:45 INFO - '--tppagecycles', 04:29:45 INFO - '7'], 04:29:45 INFO - 'tests': ['tsvgm']}}, 04:29:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:29:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:29:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:29:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:29:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:29:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:29:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:45 INFO - 'talos_options': ['--disable-e10s'], 04:29:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:29:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:29:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:45 INFO - 'talos_options': ['--disable-e10s'], 04:29:45 INFO - 'tests': ['damp', 'tps']}, 04:29:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:45 INFO - 'tests': ['damp', 'tps']}, 04:29:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:29:45 INFO - 'tests': ['dromaeo_dom']}, 04:29:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:29:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:29:45 INFO - 'tests': ['a11yr', 04:29:45 INFO - 'ts_paint', 04:29:45 INFO - 'tpaint', 04:29:45 INFO - 'sessionrestore', 04:29:45 INFO - 'sessionrestore_no_auto_restore', 04:29:45 INFO - 'tabpaint']}, 04:29:45 INFO - 'other-e10s': {'tests': ['a11yr', 04:29:45 INFO - 'ts_paint', 04:29:45 INFO - 'tpaint', 04:29:45 INFO - 'sessionrestore', 04:29:45 INFO - 'sessionrestore_no_auto_restore', 04:29:45 INFO - 'tabpaint']}, 04:29:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:29:45 INFO - 'ts_paint', 04:29:45 INFO - 'tpaint', 04:29:45 INFO - 'sessionrestore', 04:29:45 INFO - 'sessionrestore_no_auto_restore', 04:29:45 INFO - 'tabpaint']}, 04:29:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:29:45 INFO - 'ts_paint', 04:29:45 INFO - 'tpaint', 04:29:45 INFO - 'sessionrestore', 04:29:45 INFO - 'sessionrestore_no_auto_restore', 04:29:45 INFO - 'tabpaint']}, 04:29:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:29:45 INFO - 'tests': ['a11yr', 04:29:45 INFO - 'ts_paint', 04:29:45 INFO - 'tpaint', 04:29:45 INFO - 'sessionrestore', 04:29:45 INFO - 'sessionrestore_no_auto_restore', 04:29:45 INFO - 'tabpaint']}, 04:29:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:29:45 INFO - 'tests': ['a11yr', 04:29:45 INFO - 'ts_paint', 04:29:45 INFO - 'tpaint', 04:29:45 INFO - 'sessionrestore', 04:29:45 INFO - 'sessionrestore_no_auto_restore', 04:29:45 INFO - 'tabpaint']}, 04:29:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:29:45 INFO - 'tests': ['tsvgx', 04:29:45 INFO - 'tsvgr_opacity', 04:29:45 INFO - 'tart', 04:29:45 INFO - 'tscrollx', 04:29:45 INFO - 'cart']}, 04:29:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:29:45 INFO - 'tsvgr_opacity', 04:29:45 INFO - 'tart', 04:29:45 INFO - 'tscrollx', 04:29:45 INFO - 'cart']}, 04:29:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:45 INFO - 'talos_options': ['--disable-e10s'], 04:29:45 INFO - 'tests': ['tp5o']}, 04:29:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:45 INFO - 'tests': ['tp5o']}, 04:29:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:45 INFO - 'talos_options': ['--disable-e10s', 04:29:45 INFO - '--xperf_path', 04:29:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:29:45 INFO - 'tests': ['tp5n']}, 04:29:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:45 INFO - 'talos_options': ['--xperf_path', 04:29:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:29:45 INFO - 'tests': ['tp5n']}}, 04:29:45 INFO - 'talos.zip': {'path': '', 04:29:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:29:45 INFO - Running post-action listener: _resource_record_post_action 04:29:45 INFO - ##### 04:29:45 INFO - ##### Finished populate-webroot step (success) 04:29:45 INFO - ##### 04:29:45 INFO - ##### 04:29:45 INFO - ##### Running create-virtualenv step. 04:29:45 INFO - ##### 04:29:45 INFO - Running pre-action listener: _resource_record_pre_action 04:29:45 INFO - Running main action method: create_virtualenv 04:29:45 INFO - Creating virtualenv /builds/slave/test/build/venv 04:29:45 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 04:29:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:29:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:29:45 INFO - Using real prefix '/tools/python27' 04:29:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:29:46 INFO - Installing distribute.............................................................................................................................................................................................done. 04:29:49 INFO - Installing pip.................done. 04:29:49 INFO - Return code: 0 04:29:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4f856c000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f74d30>, '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 0x106d98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f6bb20>, '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.YAmW3xkmSd/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.4TVUoHcw7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:29:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:29:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YAmW3xkmSd/Render', 04:29:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:49 INFO - 'HOME': '/Users/cltbld', 04:29:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:49 INFO - 'LOGNAME': 'cltbld', 04:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:49 INFO - 'MOZ_NO_REMOTE': '1', 04:29:49 INFO - 'NO_EM_RESTART': '1', 04:29:49 INFO - 'PAGER': '/bin/cat', 04:29:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:49 INFO - 'PWD': '/builds/slave/test', 04:29:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:49 INFO - 'SHELL': '/bin/bash', 04:29:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4TVUoHcw7R/Listeners', 04:29:49 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 04:29:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:49 INFO - 'USER': 'cltbld', 04:29:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:49 INFO - 'XPC_FLAGS': '0x0', 04:29:49 INFO - 'XPC_SERVICE_NAME': '0', 04:29:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:49 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 04:29:49 INFO - Cleaning up... 04:29:49 INFO - Return code: 0 04:29:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1070d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4f856c000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f74d30>, '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 0x106d98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f6bb20>, '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.YAmW3xkmSd/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.4TVUoHcw7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:29:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:29:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YAmW3xkmSd/Render', 04:29:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:49 INFO - 'HOME': '/Users/cltbld', 04:29:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:49 INFO - 'LOGNAME': 'cltbld', 04:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:49 INFO - 'MOZ_NO_REMOTE': '1', 04:29:49 INFO - 'NO_EM_RESTART': '1', 04:29:49 INFO - 'PAGER': '/bin/cat', 04:29:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:49 INFO - 'PWD': '/builds/slave/test', 04:29:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:49 INFO - 'SHELL': '/bin/bash', 04:29:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4TVUoHcw7R/Listeners', 04:29:49 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 04:29:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:49 INFO - 'USER': 'cltbld', 04:29:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:49 INFO - 'XPC_FLAGS': '0x0', 04:29:49 INFO - 'XPC_SERVICE_NAME': '0', 04:29:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:49 INFO - Downloading/unpacking psutil>=3.1.1 04:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:29:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:29:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:29:52 INFO - Installing collected packages: psutil 04:29:52 INFO - Running setup.py install for psutil 04:29:53 INFO - building 'psutil._psutil_osx' extension 04:29:53 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 04:29:53 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 04:29:53 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 04:29:53 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 04:29:53 INFO - building 'psutil._psutil_posix' extension 04:29:53 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 04:29:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:29:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:29:53 INFO - ^ 04:29:53 INFO - 1 warning generated. 04:29:53 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 04:29:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:29:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:29:54 INFO - Successfully installed psutil 04:29:54 INFO - Cleaning up... 04:29:54 INFO - Return code: 0 04:29:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:29:54 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')]} 04:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:54 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')]} 04:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:54 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 0x1070d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4f856c000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f74d30>, '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 0x106d98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f6bb20>, '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.YAmW3xkmSd/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.4TVUoHcw7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:29:54 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 04:29:54 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 04:29:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YAmW3xkmSd/Render', 04:29:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:54 INFO - 'HOME': '/Users/cltbld', 04:29:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:54 INFO - 'LOGNAME': 'cltbld', 04:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:54 INFO - 'MOZ_NO_REMOTE': '1', 04:29:54 INFO - 'NO_EM_RESTART': '1', 04:29:54 INFO - 'PAGER': '/bin/cat', 04:29:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:54 INFO - 'PWD': '/builds/slave/test', 04:29:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:54 INFO - 'SHELL': '/bin/bash', 04:29:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4TVUoHcw7R/Listeners', 04:29:54 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 04:29:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:54 INFO - 'USER': 'cltbld', 04:29:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:54 INFO - 'XPC_FLAGS': '0x0', 04:29:54 INFO - 'XPC_SERVICE_NAME': '0', 04:29:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:29:54 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 04:29:54 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 04:29:54 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 04:29:54 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 04:29:54 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 04:29:54 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 04:29:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:29:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:29:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:29:56 INFO - Installing collected packages: mozsystemmonitor 04:29:56 INFO - Running setup.py install for mozsystemmonitor 04:29:57 INFO - Successfully installed mozsystemmonitor 04:29:57 INFO - Cleaning up... 04:29:57 INFO - Return code: 0 04:29:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:29: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')]} 04:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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')]} 04:29:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:57 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 0x1070d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4f856c000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f74d30>, '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 0x106d98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f6bb20>, '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.YAmW3xkmSd/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.4TVUoHcw7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:29:57 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 04:29:57 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 04:29:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YAmW3xkmSd/Render', 04:29:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:57 INFO - 'HOME': '/Users/cltbld', 04:29:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:57 INFO - 'LOGNAME': 'cltbld', 04:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:57 INFO - 'MOZ_NO_REMOTE': '1', 04:29:57 INFO - 'NO_EM_RESTART': '1', 04:29:57 INFO - 'PAGER': '/bin/cat', 04:29:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:57 INFO - 'PWD': '/builds/slave/test', 04:29:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:57 INFO - 'SHELL': '/bin/bash', 04:29:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4TVUoHcw7R/Listeners', 04:29:57 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 04:29:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:57 INFO - 'USER': 'cltbld', 04:29:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:57 INFO - 'XPC_FLAGS': '0x0', 04:29:57 INFO - 'XPC_SERVICE_NAME': '0', 04:29:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:57 INFO - Downloading/unpacking blobuploader==1.2.4 04:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:59 INFO - Downloading blobuploader-1.2.4.tar.gz 04:29:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:29:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:30:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:00 INFO - Downloading docopt-0.6.1.tar.gz 04:30:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:30:01 INFO - Installing collected packages: blobuploader, requests, docopt 04:30:01 INFO - Running setup.py install for blobuploader 04:30:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:30:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:30:01 INFO - Running setup.py install for requests 04:30:01 INFO - Running setup.py install for docopt 04:30:01 INFO - Successfully installed blobuploader requests docopt 04:30:01 INFO - Cleaning up... 04:30:01 INFO - Return code: 0 04:30:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30:01 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')]} 04:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:01 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')]} 04:30:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:01 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 0x1070d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4f856c000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f74d30>, '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 0x106d98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f6bb20>, '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.YAmW3xkmSd/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.4TVUoHcw7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:30:01 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 04:30:01 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 04:30:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YAmW3xkmSd/Render', 04:30:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:01 INFO - 'HOME': '/Users/cltbld', 04:30:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:01 INFO - 'LOGNAME': 'cltbld', 04:30:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:01 INFO - 'MOZ_NO_REMOTE': '1', 04:30:01 INFO - 'NO_EM_RESTART': '1', 04:30:01 INFO - 'PAGER': '/bin/cat', 04:30:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:01 INFO - 'PWD': '/builds/slave/test', 04:30:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:01 INFO - 'SHELL': '/bin/bash', 04:30:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4TVUoHcw7R/Listeners', 04:30:01 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 04:30:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:01 INFO - 'USER': 'cltbld', 04:30:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:01 INFO - 'XPC_FLAGS': '0x0', 04:30:01 INFO - 'XPC_SERVICE_NAME': '0', 04:30:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:30:02 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-FXfpuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:30:02 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-wmgRvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:30:02 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-eNulFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:30:02 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-V8H0re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:30:02 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-Li1J8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:30:02 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-YvJtpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:30:02 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-P4EYLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:30:02 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-9dUOKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:30:03 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-cCU3eV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:30:03 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-KGMK8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:30:03 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-3Vm17p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:30:03 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-Xhy8VX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:30:03 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-34I7H9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:30:03 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-OE2rtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:03 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-XSWkHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:30:03 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-vcYjvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:30:03 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-opC3jT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:30:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:30:04 INFO - Running setup.py install for manifestparser 04:30:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:30:04 INFO - Running setup.py install for mozcrash 04:30:04 INFO - Running setup.py install for mozdebug 04:30:04 INFO - Running setup.py install for mozdevice 04:30:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:30:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:30:04 INFO - Running setup.py install for mozfile 04:30:04 INFO - Running setup.py install for mozhttpd 04:30:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:30:04 INFO - Running setup.py install for mozinfo 04:30:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Running setup.py install for mozInstall 04:30:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Running setup.py install for mozleak 04:30:05 INFO - Running setup.py install for mozlog 04:30:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Running setup.py install for moznetwork 04:30:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Running setup.py install for mozprocess 04:30:05 INFO - Running setup.py install for mozprofile 04:30:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Running setup.py install for mozrunner 04:30:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:30:06 INFO - Running setup.py install for mozscreenshot 04:30:06 INFO - Running setup.py install for moztest 04:30:06 INFO - Running setup.py install for mozversion 04:30:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:30:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:30:06 INFO - Cleaning up... 04:30:06 INFO - Return code: 0 04:30:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30:06 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')]} 04:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:06 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')]} 04:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:06 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 0x1070d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4f856c000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f74d30>, '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 0x106d98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f6bb20>, '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.YAmW3xkmSd/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.4TVUoHcw7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:30:06 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 04:30:06 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 04:30:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YAmW3xkmSd/Render', 04:30:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:06 INFO - 'HOME': '/Users/cltbld', 04:30:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:06 INFO - 'LOGNAME': 'cltbld', 04:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:06 INFO - 'MOZ_NO_REMOTE': '1', 04:30:06 INFO - 'NO_EM_RESTART': '1', 04:30:06 INFO - 'PAGER': '/bin/cat', 04:30:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:06 INFO - 'PWD': '/builds/slave/test', 04:30:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:06 INFO - 'SHELL': '/bin/bash', 04:30:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4TVUoHcw7R/Listeners', 04:30:06 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 04:30:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:06 INFO - 'USER': 'cltbld', 04:30:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:06 INFO - 'XPC_FLAGS': '0x0', 04:30:06 INFO - 'XPC_SERVICE_NAME': '0', 04:30:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:30:06 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-dkehw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:30:06 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)) 04:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:30:06 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-oDlWEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:30:07 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)) 04:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:30:07 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-e5ufmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:30:07 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)) 04:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:30:07 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-C8QmeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:30:07 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)) 04:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:30:07 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-8zzr_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:30:07 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)) 04:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:30:07 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-RLjbXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:30:07 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)) 04:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:30:07 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-y_y9iv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:30:07 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)) 04:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:30:07 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-XP8fz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:30:07 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)) 04:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:30:07 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-fGNruj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:30:07 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)) 04:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:30:07 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-7FZ7HV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:30:07 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)) 04:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:30:07 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-G9i0W7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:30:08 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)) 04:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:30:08 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-MMecQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:30:08 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)) 04:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:30:08 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-rYjrLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:30:08 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)) 04:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:30:08 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-OhAU9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:30:08 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)) 04:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:08 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-pssfyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:08 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)) 04:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:30:08 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-sWLnWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:30:08 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)) 04:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:30:08 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-kcDPmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:30:08 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)) 04:30:08 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)) 04:30:08 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)) 04:30:08 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)) 04:30:08 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)) 04:30:08 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)) 04:30:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:30:08 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 04:30:08 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 04:30:08 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 04:30:08 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 04:30:08 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 04:30:08 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 04:30:11 INFO - Downloading blessings-1.6.tar.gz 04:30:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:30:11 INFO - Installing collected packages: blessings 04:30:11 INFO - Running setup.py install for blessings 04:30:11 INFO - Successfully installed blessings 04:30:11 INFO - Cleaning up... 04:30:11 INFO - Return code: 0 04:30:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:30:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:30:11 INFO - Reading from file tmpfile_stdout 04:30:11 INFO - Current package versions: 04:30:11 INFO - blessings == 1.6 04:30:11 INFO - blobuploader == 1.2.4 04:30:11 INFO - docopt == 0.6.1 04:30:11 INFO - manifestparser == 1.1 04:30:11 INFO - mozInstall == 1.12 04:30:11 INFO - mozcrash == 0.17 04:30:11 INFO - mozdebug == 0.1 04:30:11 INFO - mozdevice == 0.48 04:30:11 INFO - mozfile == 1.2 04:30:11 INFO - mozhttpd == 0.7 04:30:11 INFO - mozinfo == 0.9 04:30:11 INFO - mozleak == 0.1 04:30:11 INFO - mozlog == 3.2 04:30:11 INFO - moznetwork == 0.27 04:30:11 INFO - mozprocess == 0.22 04:30:11 INFO - mozprofile == 0.28 04:30:11 INFO - mozrunner == 6.11 04:30:11 INFO - mozscreenshot == 0.1 04:30:11 INFO - mozsystemmonitor == 0.3 04:30:11 INFO - moztest == 0.7 04:30:11 INFO - mozversion == 1.4 04:30:11 INFO - psutil == 3.1.1 04:30:11 INFO - requests == 1.2.3 04:30:11 INFO - wsgiref == 0.1.2 04:30:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30:11 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')]} 04:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:11 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')]} 04:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:11 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 0x1070d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4f856c000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f74d30>, '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 0x106d98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f6bb20>, '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.YAmW3xkmSd/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.4TVUoHcw7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:30:11 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 04:30:11 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 04:30:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YAmW3xkmSd/Render', 04:30:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:11 INFO - 'HOME': '/Users/cltbld', 04:30:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:11 INFO - 'LOGNAME': 'cltbld', 04:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:11 INFO - 'MOZ_NO_REMOTE': '1', 04:30:11 INFO - 'NO_EM_RESTART': '1', 04:30:11 INFO - 'PAGER': '/bin/cat', 04:30:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:11 INFO - 'PWD': '/builds/slave/test', 04:30:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:11 INFO - 'SHELL': '/bin/bash', 04:30:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4TVUoHcw7R/Listeners', 04:30:11 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 04:30:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:11 INFO - 'USER': 'cltbld', 04:30:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:11 INFO - 'XPC_FLAGS': '0x0', 04:30:11 INFO - 'XPC_SERVICE_NAME': '0', 04:30:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 INFO - Cleaning up... 04:30:12 INFO - Return code: 0 04:30:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:30:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4f856c000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f74d30>, '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 0x106d98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f6bb20>, '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.YAmW3xkmSd/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.4TVUoHcw7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:30:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:30:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:30:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YAmW3xkmSd/Render', 04:30:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:12 INFO - 'HOME': '/Users/cltbld', 04:30:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:12 INFO - 'LOGNAME': 'cltbld', 04:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:12 INFO - 'MOZ_NO_REMOTE': '1', 04:30:12 INFO - 'NO_EM_RESTART': '1', 04:30:12 INFO - 'PAGER': '/bin/cat', 04:30:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:12 INFO - 'PWD': '/builds/slave/test', 04:30:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:12 INFO - 'SHELL': '/bin/bash', 04:30:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4TVUoHcw7R/Listeners', 04:30:12 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 04:30:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:12 INFO - 'USER': 'cltbld', 04:30:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:12 INFO - 'XPC_FLAGS': '0x0', 04:30:12 INFO - 'XPC_SERVICE_NAME': '0', 04:30:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:12 INFO - Downloading/unpacking jsonschema 04:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:30:14 INFO - Downloading/unpacking functools32 (from jsonschema) 04:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:15 INFO - Downloading functools32-3.2.3-2.tar.gz 04:30:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:30:15 INFO - warning: no files found matching '*.txt' 04:30:15 INFO - no previously-included directories found matching 'build' 04:30:15 INFO - no previously-included directories found matching 'dist' 04:30:15 INFO - no previously-included directories found matching '.git*' 04:30:15 INFO - Installing collected packages: jsonschema, functools32 04:30:15 INFO - Running setup.py install for functools32 04:30:15 INFO - warning: no files found matching '*.txt' 04:30:15 INFO - no previously-included directories found matching 'build' 04:30:15 INFO - no previously-included directories found matching 'dist' 04:30:15 INFO - no previously-included directories found matching '.git*' 04:30:15 INFO - Successfully installed jsonschema functools32 04:30:15 INFO - Cleaning up... 04:30:15 INFO - Return code: 0 04:30:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:15 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 0x1070d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4f856c000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f74d30>, '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 0x106d98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f6bb20>, '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.YAmW3xkmSd/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.4TVUoHcw7R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:30:15 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 04:30:15 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 04:30:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YAmW3xkmSd/Render', 04:30:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:15 INFO - 'HOME': '/Users/cltbld', 04:30:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:15 INFO - 'LOGNAME': 'cltbld', 04:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:15 INFO - 'MOZ_NO_REMOTE': '1', 04:30:15 INFO - 'NO_EM_RESTART': '1', 04:30:15 INFO - 'PAGER': '/bin/cat', 04:30:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:15 INFO - 'PWD': '/builds/slave/test', 04:30:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:15 INFO - 'SHELL': '/bin/bash', 04:30:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4TVUoHcw7R/Listeners', 04:30:15 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 04:30:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:15 INFO - 'USER': 'cltbld', 04:30:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:15 INFO - 'XPC_FLAGS': '0x0', 04:30:15 INFO - 'XPC_SERVICE_NAME': '0', 04:30:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:15 INFO - Downloading/unpacking flake8 04:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:30:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:30:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:30:19 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 04:30:19 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 04:30:19 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 04:30:19 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 04:30:19 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 04:30:19 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 04:30:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:30:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:30:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:30:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:30:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:30:19 INFO - no previously-included directories found matching 'docs/_build' 04:30:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:30:19 INFO - Running setup.py install for pep8 04:30:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:30:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:30:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:30:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:30:19 INFO - no previously-included directories found matching 'docs/_build' 04:30:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:30:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:30:19 INFO - Cleaning up... 04:30:19 INFO - Return code: 0 04:30:19 INFO - Running post-action listener: _resource_record_post_action 04:30:19 INFO - Running post-action listener: _start_resource_monitoring 04:30:19 INFO - Starting resource monitoring. 04:30:19 INFO - ##### 04:30:19 INFO - ##### Finished create-virtualenv step (success) 04:30:19 INFO - ##### 04:30:19 INFO - ##### 04:30:19 INFO - ##### Running install step. 04:30:19 INFO - ##### 04:30:19 INFO - Running pre-action listener: _resource_record_pre_action 04:30:19 INFO - Running main action method: install 04:30:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:30:20 INFO - Reading from file tmpfile_stdout 04:30:20 INFO - Detecting whether we're running mozinstall >=1.0... 04:30:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:30:20 INFO - Reading from file tmpfile_stdout 04:30:20 INFO - Output received: 04:30:20 INFO - Usage: mozinstall [options] installer 04:30:20 INFO - Options: 04:30:20 INFO - -h, --help show this help message and exit 04:30:20 INFO - -d DEST, --destination=DEST 04:30:20 INFO - Directory to install application into. [default: 04:30:20 INFO - "/builds/slave/test"] 04:30:20 INFO - --app=APP Application being installed. [default: firefox] 04:30:20 INFO - mkdir: /builds/slave/test/build/application 04:30:20 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'] 04:30:20 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 04:30:54 INFO - Reading from file tmpfile_stdout 04:30:54 INFO - Output received: 04:30:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:30:54 INFO - Running post-action listener: _resource_record_post_action 04:30:54 INFO - ##### 04:30:54 INFO - ##### Finished install step (success) 04:30:54 INFO - ##### 04:30:54 INFO - ##### 04:30:54 INFO - ##### Running run-tests step. 04:30:54 INFO - ##### 04:30:54 INFO - Running pre-action listener: _resource_record_pre_action 04:30:54 INFO - Running main action method: run_tests 04:30:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:30:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:30:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:30:54 INFO - 2016-06-03 04:30:54.159 screenresolution[1655:8409] starting screenresolution argv=screenresolution get 04:30:54 INFO - 2016-06-03 04:30:54.162 screenresolution[1655:8409] Display 0: 1600x1200x32@60 04:30:54 INFO - 2016-06-03 04:30:54.167 screenresolution[1656:8411] starting screenresolution argv=screenresolution list 04:30:54 INFO - Available Modes on Display 0 04:30:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:30:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:30:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:30:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:30:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:30:54 INFO - Intel Iris: 04:30:54 INFO - Chipset Model: Intel Iris 04:30:54 INFO - Type: GPU 04:30:54 INFO - Bus: Built-In 04:30:54 INFO - VRAM (Dynamic, Max): 1536 MB 04:30:54 INFO - Vendor: Intel (0x8086) 04:30:54 INFO - Device ID: 0x0a2e 04:30:54 INFO - Revision ID: 0x0009 04:30:54 INFO - Displays: 04:30:54 INFO - Display: 04:30:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:30:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:30:54 INFO - Display Serial Number: 1600x1200 60 04:30:54 INFO - Main Display: Yes 04:30:54 INFO - Mirror: Off 04:30:54 INFO - Online: Yes 04:30:54 INFO - Rotation: Supported 04:30:54 INFO - Return code: 0 04:30:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:30:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:30:54 INFO - Python 2.7.3 04:30:54 INFO - Return code: 0 04:30:54 INFO - grabbing minidump binary from tooltool 04:30:54 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')]} 04:30:54 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 0x106f74d30>, '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 0x106d98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f6bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:30:54 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 04:30:54 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 04:30:54 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 04:30:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:30:54 INFO - Return code: 0 04:30:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:30:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:30:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:30:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:30:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:30:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:30:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:30:55 INFO - Return code: 0 04:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0389', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0389 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:30:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YAmW3xkmSd/Render', 04:30:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:55 INFO - 'HOME': '/Users/cltbld', 04:30:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:55 INFO - 'LOGNAME': 'cltbld', 04:30:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:30:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:55 INFO - 'MOZ_NO_REMOTE': '1', 04:30:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:30:55 INFO - 'NO_EM_RESTART': '1', 04:30:55 INFO - 'PAGER': '/bin/cat', 04:30:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:55 INFO - 'PWD': '/builds/slave/test', 04:30:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:30:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:55 INFO - 'SHELL': '/bin/bash', 04:30:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4TVUoHcw7R/Listeners', 04:30:55 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 04:30:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:55 INFO - 'USER': 'cltbld', 04:30:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:55 INFO - 'XPC_FLAGS': '0x0', 04:30:55 INFO - 'XPC_SERVICE_NAME': '0', 04:30:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0389', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:30:55 INFO - mozversion application_buildid: 20160603022511 04:30:55 INFO - mozversion application_changeset: 10371d73bb08fa730e311885433bd147bf2a3576 04:30:55 INFO - mozversion application_display_name: Nightly 04:30:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:30:55 INFO - mozversion application_name: Firefox 04:30:55 INFO - mozversion application_remotingname: firefox 04:30:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:30:55 INFO - mozversion application_vendor: Mozilla 04:30:55 INFO - mozversion application_version: 49.0a1 04:30:55 INFO - mozversion platform_buildid: 20160603022511 04:30:55 INFO - mozversion platform_changeset: 10371d73bb08fa730e311885433bd147bf2a3576 04:30:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:30:55 INFO - mozversion platform_version: 49.0a1 04:30:55 INFO - using testdate: 1464953455 04:30:55 INFO - actual date: 1464953455 04:30:55 INFO - starting webserver on 'localhost:49276' 04:30:55 INFO - SUITE-START | Running 2 tests 04:30:55 INFO - TEST-START | dromaeo_css 04:30:55 INFO - operating with platform_type : mac_ 04:30:55 INFO - Initialising browser for dromaeo_css test... 04:30:55 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/tmpvev4R6/profile http://localhost:49276/getInfo.html) 04:30:58 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 04:30:58 INFO - PROCESS | 1674 | colorDepth:24 04:30:58 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 04:30:58 INFO - PROCESS | 1674 | __metrics 04:30:58 INFO - PROCESS | 1674 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:30:58 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:30:59 INFO - TEST-INFO | 1674: exit 0 04:30:59 INFO - Browser initialized. 04:30:59 INFO - Running cycle 1/1 for dromaeo_css test... 04:30:59 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/tmpvev4R6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:31:11 INFO - PROCESS | 1677 | RSS: Main: 242114560 04:31:11 INFO - PROCESS | 1677 | 04:32:02 INFO - PROCESS | 1677 | 1464953522271 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] 04:34:13 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 04:34:14 INFO - PROCESS | 1677 | RSS: Main: 393748480 04:34:14 INFO - PROCESS | 1677 | 04:37:18 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 04:37:18 INFO - PROCESS | 1677 | RSS: Main: 378339328 04:37:18 INFO - PROCESS | 1677 | 04:40:21 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 04:40:22 INFO - PROCESS | 1677 | RSS: Main: 373649408 04:40:22 INFO - PROCESS | 1677 | 04:43:24 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 04:43:24 INFO - PROCESS | 1677 | RSS: Main: 371941376 04:43:24 INFO - PROCESS | 1677 | 04:46:28 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 04:46:28 INFO - PROCESS | 1677 | RSS: Main: 342691840 04:46:28 INFO - PROCESS | 1677 | 04:49:31 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 04:49:32 INFO - PROCESS | 1677 | RSS: Main: 378982400 04:49:32 INFO - PROCESS | 1677 | 04:49:32 INFO - PROCESS | 1677 | __start_tp_report 04:49:32 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 04:49:32 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 04:49:32 INFO - PROCESS | 1677 | |i|pagename|runs| 04:49:32 INFO - PROCESS | 1677 | |0;dojo.html;3420.8289585520724;3445.620981692012;3453.844576994035;3457.965420345796;3510.80690562019;5534.225208470817;5545.473180047899;5623.606347555673;5633.746481408341;5678.630888992213;5577.414371490996;5595.020871347519;5710.428957104287;5711.914321285184;5765.711714414283;5049.570786483153;5052.191649336107;5121.206213036985;5149.0731668299695;5209.426963034082;5196.038732834419;5228.686278823283;5236.685798852062;5259.027079990195;5261.973690131563;5037.798488060492;5066.670666406671;5087.9745601272125;5109.335786347789;5136.845894623168;5144.996725638502;5152.768125434364;5158.123119069767;5158.535731784157;5180.248863914749;5081.517255860687;5106.770195341219;5123.462036486152;5134.101532231882;5151.4333423323405;5525.254090697785;5525.64083334586;5585.664860108366;5608.831735047955;5650.74571644277;6084.739250410383;6104.145419641254;6129.38706129388;6173.81478555644;6191.288001879797;6181.752729890799;6214.533909956771;6248.1564988726395;6251.562390632657;6264.029075493306;5605.159226116074;5638.464345887134;5720.771169153188;5729.25519682447;5808.883822323614;6343.238811342576;6380.904286435707;6393.840153996187;6398.9040164397575;6415.550911436155;6093.472981630176;6094.235304088621;6094.476380904774;6099.9137557143285;6111.113888486145;4991.80032798684;6070.757169713162;6124.755009799647;6137.223770008841;6139.8601398601395;6105.64170830808;6122.215193971453;6130.275842464154;6136.519078024463;6140.749659041455;6089.727580591997;6145.647957449354;6153.7230824613;6157.599756015845;6157.96921015392;4793.9280910786365;4834.854954351375;4850.199717046718;4884.1941079722155;4936.333594964705;4364.367166760865;4372.650187984954;4382.474103107584;4386.232409328419;4438.001449673798;5611.410801865827;5628.71856407178;5631.267935168402;5641.210230567724;5686.516646085046;5966.731497082642;6057.091436284592;6076.8784624308155;6098.099428985345;6237.126802247598;5509.00837849191;5550.306211723535;5563.276774019432;5567.359753628303;5573.052581061229;5298.6290959633225;5302.549283310885;5307.708076055854;5323.30796996388;5361.249425080492;6336.841578960563;6350.587211831216;6350.714217860207;6377.23473183221;6378.808635740936;6179.62922224668;6257.78097766576;6259.7496100157305;6267.749290028348;6270.216222972129;6252.718627661675;6299.811005669837;6361.268454127835;6362.554621176659;6371.267304260069;6127.403330890726;6133.731528139169;6138.878900475605;6144.907826382519;6148.877022459616;6214.720337584729;6235.875282494416;6241.375862413723;6242.188515492957;6252.437280644764;5798.449147330997;5865.794697185578;5866.5893387462465;5890.440408161217;5969.582129250911;5884.558658100574;5917.319508256605;5987.850303742267;5994.790182343771;6098.29869564988;5890.676012819336;5899.970500147472;5903.409659034234;5955.67243801595;5971.373005834324;6009.2188015557695;6027.78902738402;6046.969765151146;6059.939510585542;6088.543359248163;5727.5417966561745;5807.680577568274;5809.912851307149;5876.882462350814;5883.852903677443 04:49:32 INFO - PROCESS | 1677 | |1;ext.html;479.6402697976518;504.96482211466486;508.66173994293797;520.7786690656473;552.3840917377125;30460.695393046062;41988.37017444737;42472;42491.57508424915;42551.14897702044;43961.56038439614;44044;44368.55631443684;44430.55569444304;44537.55462445375;7360;9440.291978101635;9644.22846172308;9650.71047868565;9675.854862177073;8632.611532481053;11672.883271167308;11903.880961190363;11915.642530724048;12194.573189938354;11825;11915.7616847663;11924.463399147016;11926.522939082426;11926.986206172442;8613.397062205648;11629.011534019619;11749.7650046999;11893.226940248856;11941.044716422708;11713.94143029288;11851.822222666668;11860.881391186063;11860.881391186063;11870.287782732978;40471.392929106085;40997.59002409968;41053.178936421406;41069.1786164275;41086;3548.964510354889;3574.535310409656;3586.264815712773;3624.9818750906074;3626.7279954003557;28212.448315342648;29356.412871742446;31833.204169895707;32629.51055734166;32674.509882351784;2858.6712528059334;3174.380995705815;3194.712475877159;3273.967260327414;3421.1105112670634;481044;483630;484620;484844;484989;63806.36193638097;64142;64151;64202;64326.67836660787;67283.66358168177;67305;67323.32676673269;67381.66309168423;67426;15210.847891520943;16706.83293167077;16881.57796055084;16894.239759210865;17031.063291519087;11651.708707282385;12152.027837772957;12937.288449135387;12947.805782913263;12952.546660866825;17328.653426931643;17779;17787.644247114986;17907.37324193647;17930.55173620644;5785.710714464344;6388.5182575496465;7378.409727221917;7382.409407247407;7398.593077380688;911.1935936696071;1005.939643621385;1053.951823594435;1101.8126918423727;1125.5722825326325;27305;29055.983040593477;31344.37311253762;31594.842025789723;32584.85952991634;882.9072947340566;1106.8063088959561;1106.8671759388765;1128.6557599931934;1201.0751721174647;16274.593135171715;16638.83361166373;17080;17118.229679664444;17255.39606113805;669882;672852;675209;677432;681568;61515.692421537606;61529.077063843186;61705.691471542355;61709.69145154246;61761.69119154375;1284.13321008319;1414.7736362181795;1432.5201057645597;1444.4944269505588;1475.5942115918208;29113.85443072771;31382.529262061544;31482.212944676565;31635.84182079075;32298;51129.48870511396;51601;51741.48258517367;51868.740656296475;51875.74062129665;562464;564300.3569964413;564562;566599;569917;22925.082996679947;23185.884070580214;25849.612255815802;25894.352641183366;27821.30446738767;47488.762556187;48413.5158648409;48509.5149048505;48875.755621221666;49475;38612;38647.42028869513;39005.41491877567;39894.2021159581;40075.198496029334;38425.80787096047;39436.014099647735;39533.604663954146;39945.80027099846;40650;39307.6069239304;39891.00272493211;40273;40544.594554054085;40568.594314056485;28957.131286061453;32300.67699322977;34594.135146621535;35600.10999724924;35733.82133089318;24667;24750.628740569267;26080.608790867773;26105.216843494727;26608.20175394741 04:49:32 INFO - PROCESS | 1677 | |2;jquery.html;376.5048960616789;383.5608228578278;386.1851493349034;403.47145239735966;412.48027485368425;19763;21061.368158955254;21360.825154616472;21396.679049814265;22107.668384974237;20970.475738106517;21087.68368474474;22054.117835286608;22079.227227047057;22113;5267.604929630274;5765.711714414273;5943.375945525722;6159.938400615982;6170.7223174957235;4241.130568233505;4469.955300447003;4479.3952816369865;5998.520118390518;6557.967210163943;6721.831954201135;6828.52200345976;6842.726290948356;6843.110395648584;6866.141732283465;6575.473962083021;6755.256921738604;6758.425533829631;6805.387515123638;6831.282715314895;3685.207680348738;5511.669299842022;5877.529797616223;5881.588288819784;5918.496927761145;20170.69743953842;25450.872745636338;25509.61735573968;25816.354591135183;25857.612135817977;2234.4637287051155;2269.2851751698163;2472.505498900209;2473.208573256559;2703.2836298380944;13296.335183240799;15275.61810954735;15281.541553753294;15301.846981530265;15995.600109997227;1840.484664294;1877.9248830046918;1918.4054190214358;2011.9195232190696;2024.645687004783;104974.47512762464;109317.45341273323;109358.81282374388;109766;111164.88835111626;10012.349197302152;10172.796544069226;10838.837417438745;11354.091672666333;11408.429578521042;13941.651458713613;14024.579262622137;14049.78925316121;14055.297235138201;14056.22690751998;8620.95689521561;8834.469931804113;8855.73432797017;8929.910700892908;8947.821043579223;8892.421992570462;8895.599698013604;8917.063708310663;9037.057032737994;9045.366824322235;6644.136262285969;6769.32306769329;6774.661266936633;6801.727930882808;7056.400205982446;1724.3102758896541;1757.6221112460883;1819.736138259945;1853.4717605482642;1873.6627407066858;852.3692467574075;926.7590426489162;953.2897990996752;976.4336684722844;1056.070657821128;16965.915170424316;20732.58534829295;22015.559688806134;22196.223132190295;22315.888420557792;966.3477152921749;966.5940305071887;977.6236149082512;980.6861804222581;1018.9286749927434;11593.420328983517;11865.986730273751;12006.439162908766;12009.63971080869;12040;476253;500593;601168;609886;610548;134586.32706836404;136089;141599;145569;147240;921.9585118669676;950.8526178442353;968.6609686609771;990.5294984882123;992.8830066175552;18041.458756237334;18504.1673124707;19097;19353.903230483756;19800.801991979897;43898;47254.527454725016;50431.495685044145;51062.489375105775;52554.73722631362;70730.29269707443;70898.64550677214;72889.27110728825;73051.26948730445;74302.25697742954;7748.690052397841;8006.7333399346935;8032.437729358889;8091.474054186459;8245.628946697292;8839.381243312906;11467.827982580102;12215.69460763488;12440;12522.123451358682;8526.701565445179;10617.946910265398;10810.35137891729;10904;11178.385188814693;9701.805963880824;10636.52135653882;10661.733456663645;10796.946015269874;10919.235653504178;8624.310055195443;11042.558297668325;11228.88771112311;11233.775324493628;11234.775304493702;7907.84184316299;8077.273045425704;8083.232092951161;8094.919050809652;8213.466124701876;9644.421334719938;10979.835302470628;11060.723481912695;11130.666080017612;11144.331340119294 04:49:32 INFO - PROCESS | 1677 | |3;mootools.html;1359.9936047324982;1519.2631573686763;1554.292796777466;1757.3322137587716;1940.922363105475;7721.884171737422;8499.702510412137;8628.654853805856;8637.309015278779;8831.779205519866;8344.749657510269;8593.828123437528;8600.35497337699;8602.655893764242;8611.870821937677;2093.591749608819;2297.2304278066867;2341.672165896776;2366.148186652804;2378.916737914173;2377.8573285602833;2399.040383846458;2406.578848701479;2408.0247499762536;2409.0604664180983;2331.7668233176632;2355.328731311568;2369.6327069304198;2370.893309801063;2370.988145059281;2079.2202923903537;2270.67075274086;2321.025169428844;2338.5150429477235;2342.1685301717816;2363.078399424226;2388.5103553771437;2390.8087353011892;2392.7248366437734;2394.9941024769464;7373.225811289791;8606.35452341077;8606.44058136219;8683.435576687496;8687.956560217222;4262;5064.797408103708;5271.815486457956;5279.894402111936;5286.259923610698;3448.5516882805327;3538.4692296155526;3605.963940360615;3609.3322735294055;3639;1000.2897942460861;1042.5204405973261;1046.5447530324257;1050.2648146297622;1055.0451841083832;11465.942670286679;11476.540938362456;11481.712957176054;11497.252678575866;11515.30908145514;4589.449266088091;4628.791704373311;4755.310479980452;4771.284307353924;4771.618270538349;9717.319787614799;9717.514124293757;9725.221982241404;9726.659566915125;9728.978457262026;5202.791888324425;5300.09898317287;5302.416734159239;5302.628815982921;5303.840884773463;4410.470743510735;4558.566936141061;4643.512431194744;4840.733759643183;4855.295982082578;3845.5000849889334;3926.6466018058513;4025.3961905714377;4060.4112403701297;4073.6556936210977;1105.6897576371864;1256.5036810459817;1278.6291975327051;1285.1389568988614;1301.7656821771982;915.2494954137676;938.2025278513346;940.7177846645978;941.1576638908139;1007.4106647611083;5682.346530149003;5691.317041954909;5780.797672081457;5797.623154494944;5921.111833224964;436.79034063649624;445.18086720434553;446.09442831053013;446.5155306492427;465.855584768716;3888.1446081861945;3962.8612998544913;4002.519697636244;4019.7789121598007;4026.6778657707314;10072.68328284484;10406.895931040744;10564.102051325723;10570.894291056991;10582.100521455608;10393.792124157626;10431.11335536488;10509.84235236472;10513.632022879163;10525.631602893862;7693.615319234016;7866.780648999415;8019.39854510914;8026.036875574969;8026.678932842738;4867.80528778859;5703.287089113861;5897.351291357862;5960.701964901824;6115.082737589397;9342.579583918605;9865.210783137476;9875.506224688594;9890.950545247228;9908.554115064695;9681.564329605326;9931.056549627774;10000.349977251455;10115.89884101179;10126.645567405105;7252.891206631799;7284.162321333115;7402.925970740223;7448.068991376078;7507.962460187664;8123.79690507742;8134.471259368122;8222.506649601042;8230.21812927771;8242.464239824392;7026.929730702832;7335.523190992569;7340.045794046953;7415.406767458699;7475.327220550176;7070.540414873222;7125.394341481032;7133.108361454818;7266.7093316266755;7267.164276108104;6838.623875686885;6851.314868513109;6895.482838787011;6896.689648965708;6925.376716095576;4785.875319299892;4915.18899381598;4940.431850337113;4968.378952630921;5016.749162541932;4237.660987121085;4288.785560721889;4297.806598703129;4925.039617274597;4982.676126051795 04:49:32 INFO - PROCESS | 1677 | |4;prototype.html;289.3634005188585;312.01403564735426;315.84681429506685;316.7291965369609;321.50006739520035;5132.230165475181;5132.615053870961;5152.458991805854;5176.974115129424;5186.611004174683;3994.4208089826957;4695.46002209746;5126.8720881406125;5127.923081153776;5161.406438259602;3677.4667673187373;3815.3513902636478;3861.3435715928217;3878.8836334909997;3881.28196283687;3726.9440958385644;3804.353259945816;3852.5184351956004;3907.960920390802;3946.6448019678223;3387.6104248011547;3749;3913.902152446183;3927.6857851371824;3933.9213215735667;3882.3594106972314;3904.160605469809;3909.882703518899;3944;4070.531888832763;2866.885324587014;3194;3290.2925870937624;3345.7156141727983;3452.6202117767025;4485.327200919888;4927.679700819435;4931.312434723084;4937.851864444073;4968.22992436173;4097.590240975908;4200.991761977134;4449.28811390176;4467.642588592905;4486.820527178909;4571.1658771561515;5009.774560144766;5366.517013468807;5401.731272865367;5435.320584926884;3548.840302186408;3706.2395362203006;3708.6662200401825;3711.777293362407;3722.7021838252876;5296.126139187029;5517.806876759294;5536.99999999996;5640.379558248589;5678.829635110953;2839.616651751987;3396.8811091611674;3611.8735844245853;3618.8371523281517;3622.8550857965915;3488.4767284907466;3626.9455958160647;3778;3785.9242815144094;3809.6570609075775;4224.809883555247;4379.912401751947;4383.452068491439;4385.210662080856;4449.2658711312915;4768.427788665383;5081.4918508148885;5280.917411930545;5431.972840135853;5435.701036442954;1152.5965911930925;1155.9884401156048;1265.234533107475;1284.48430851596;1294.242867922276;749.7563291930145;779.653054390802;789.9881501777478;790.7548659915444;792.5165648954134;3798.658120769089;4061.431399604058;4268.679849011337;4290.699651024461;4291.935620965688;3952.9209415812097;4314;4325.545817689161;4333.566643335705;4368.628666563314;3772.8113594320175;4007.73949693269;4020.7587544747407;4242.936355954663;4283.400323954649;2113.3554265949033;2372.632242002492;2466.445049863766;2470.493548822487;2471.938201544976;4301.569843015736;4818.879528011757;4833.3958255218095;4846.854594362175;4868.92883565615;4004.3392840181045;4629.907401851944;4752.693009422367;4767.928481072787;4776.498467660915;4647.9767601161775;4828.95171048292;5153.6907785532985;5471.781128754965;5824.359320474744;4784.449788274393;5255.710935898563;5642.548596112383;5652.664927692516;5655.575831812713;4791.161546729378;5601.031453329741;5626.38109807929;5626.5780066496;5630.662160270397;5495.9832431000395;5804.332501762183;5827.912581311369;5898.144769008555;5902.852428689317;3324.4016077106353;3710.8515659373325;3747.587765345864;3755.5916531300763;3757;5323.228131920772;5520.006398848085;5584.246126772834;5590.440955904377;5591.189277554813;4878.341423907727;5424.294841670558;5486.478784515464;5487.368952570346;5515.944840551543;5387.757550910297;5423.566114710735;5423.945760542344;5437.749317656882;5540.252065971043;4103.692223083341;4714.882127946828;5314.4951229633125;5323.574114070942;5343.946560534345;5177.534021938043;5438.646487978269;5451.3458384994055;5459.508644222039;5462.754176062007;5114.4629813869005;5131.615128865425;5345.278387417804;5361.597880158925;5366.302380690485 04:49:32 INFO - PROCESS | 1677 | |5;yui.html;703.9084918960534;728.9744858929937;761.3338328962158;803.208839293296;827.5448503323171;15341.923290383547;15458.072515649055;15722.842771572296;15742.527724168265;15745.60635984101;14174.291285435738;14183.290835458212;14249.501267455642;14260.643483912907;14260.857391426083;692.4356649330779;711.2070041903266;864.7924498120453;868.0494858130337;893.1381217125472;880.8986966498838;884.1203003012012;891.3047822698305;893.8167675626481;894.575076838503;937.976550586234;940.05994005994;940.92472602192;946.1957336264189;947.2564037230775;732.7325526182958;786.268770043859;915.0849150849151;934.6308208257734;942.236788201559;661.8543920337518;663.2869665109978;663.9568428052161;664.3754870421836;668.8762578922915;14624.853751462562;14672.19302938338;14689.338979745937;14691.853081469155;14700;13495.257760823151;13833;13845;13881.861181388158;14019.369128389146;12662.936685316607;12762.872371276262;12855.16441431304;12927.288999105045;13282;4425.845095421645;4486.038753426501;4575.58819706228;4662.090892275974;4700.412448443944;88567;88803;88907.55546222292;89100;89153.77115572098;9254.629814807333;9975.052370024836;10209.540570674337;10376.740581485372;11510.94244528772;21329.78670213309;21597.892010539847;21661.891690541448;21666.56666866654;21680.89159554192;7397.889031664529;7917.0895347034675;7922.207779222032;7991.480553763987;8230.300424463987;4314.532401341226;4381.277089280296;4388.425280109581;4390.670699697535;4575.0392417592675;3360.479125735515;3499.3001399720156;3500.439929611301;3502.9124271893406;3507.9298414031578;629.1129507394552;648.335456157433;652.9477641788645;665.4077870695086;709.7196607340072;573.9339975902741;673.1686367336335;685.3146853146853;685.9382655560923;692.3630260160664;9746.75633109178;9869.259805514472;9907.950460247652;9934.602615895428;9979.251556133318;552.1221258199482;588.2969851027981;693.6427739714052;701.7228194863103;711.7010855440624;4487.6185524230805;4758.857234282977;4768.165571025057;4770.475247722748;4782.736949467812;97557;117267.41366293284;153132.46867531183;153209;157174.21412892864;104051.47974260231;128555;129929.35035324763;129995;131312.3434382822;93460.53269733608;111002.44498777455;112440;112573.43713281381;112983;8481.533515656698;9122.817543649222;9156.17594416479;9258.95370523143;9282.257419406566;57236;60926.69536652289;61970.69014654898;62334.68832655808;63367.366326336145;86606;92619;96117.5194124053;96842;97360;11759.88240117622;11965.401729913643;12134.150609457252;12156;12203.816942746043;13335.733285334432;13494.662633434242;13511.662208444868;13684.52104176349;13870.514531991332;12313.13808033465;12549.49802007946;12651;12981.545645902348;13071.346432678138;12410.937945310216;12588.244705317711;12816.55142070023;12886.806697899352;13028.674283142997;12122;12175.939120304341;12388.442520086437;12447.751044979232;12648.17786843889;10400.791984160123;10625;10724.410157441416;10742.40916749555;10969.012788848722;10148;10486.423246721504;10548;10570.312929659547;10590.8411373831 04:49:32 INFO - PROCESS | 1677 | __end_tp_report 04:49:32 INFO - PROCESS | 1677 | __start_cc_report 04:49:32 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1559 04:49:32 INFO - PROCESS | 1677 | __end_cc_report 04:49:32 INFO - PROCESS | 1677 | __startTimestamp1464954572158__endTimestamp 04:49:32 INFO - PROCESS | 1677 | 04:49:32 INFO - PROCESS | 1677 | ------- Summary: start ------- 04:49:32 INFO - PROCESS | 1677 | Number of tests: 6 04:49:32 INFO - PROCESS | 1677 | 04:49:32 INFO - PROCESS | 1677 | [#0] dojo.html Cycles:165 Average:5683.38 Median:5876.88 stddev:630.31 (10.7%) stddev-sans-first:606.74 04:49:32 INFO - PROCESS | 1677 | Values: 3420.8 3445.6 3453.8 3458.0 3510.8 5534.2 5545.5 5623.6 5633.7 5678.6 5577.4 5595.0 5710.4 5711.9 5765.7 5049.6 5052.2 5121.2 5149.1 5209.4 5196.0 5228.7 5236.7 5259.0 5262.0 5037.8 5066.7 5088.0 5109.3 5136.8 5145.0 5152.8 5158.1 5158.5 5180.2 5081.5 5106.8 5123.5 5134.1 5151.4 5525.3 5525.6 5585.7 5608.8 5650.7 6084.7 6104.1 6129.4 6173.8 6191.3 6181.8 6214.5 6248.2 6251.6 6264.0 5605.2 5638.5 5720.8 5729.3 5808.9 6343.2 6380.9 6393.8 6398.9 6415.6 6093.5 6094.2 6094.5 6099.9 6111.1 4991.8 6070.8 6124.8 6137.2 6139.9 6105.6 6122.2 6130.3 6136.5 6140.7 6089.7 6145.6 6153.7 6157.6 6158.0 4793.9 4834.9 4850.2 4884.2 4936.3 4364.4 4372.7 4382.5 4386.2 4438.0 5611.4 5628.7 5631.3 5641.2 5686.5 5966.7 6057.1 6076.9 6098.1 6237.1 5509.0 5550.3 5563.3 5567.4 5573.1 5298.6 5302.5 5307.7 5323.3 5361.2 6336.8 6350.6 6350.7 6377.2 6378.8 6179.6 6257.8 6259.7 6267.7 6270.2 6252.7 6299.8 6361.3 6362.6 6371.3 6127.4 6133.7 6138.9 6144.9 6148.9 6214.7 6235.9 6241.4 6242.2 6252.4 5798.4 5865.8 5866.6 5890.4 5969.6 5884.6 5917.3 5987.9 5994.8 6098.3 5890.7 5900.0 5903.4 5955.7 5971.4 6009.2 6027.8 6047.0 6059.9 6088.5 5727.5 5807.7 5809.9 5876.9 5883.9 04:49:32 INFO - PROCESS | 1677 | 04:49:32 INFO - PROCESS | 1677 | [#1] ext.html Cycles:180 Average:71546.40 Median:3625.85 stddev:155018.83 (4275.4%) stddev-sans-first:155361.87 04:49:32 INFO - PROCESS | 1677 | Values: 479.6 505.0 508.7 520.8 552.4 30460.7 41988.4 42472.0 42491.6 42551.1 43961.6 44044.0 44368.6 44430.6 44537.6 7360.0 9440.3 9644.2 9650.7 9675.9 8632.6 11672.9 11903.9 11915.6 12194.6 11825.0 11915.8 11924.5 11926.5 11927.0 8613.4 11629.0 11749.8 11893.2 11941.0 11713.9 11851.8 11860.9 11860.9 11870.3 40471.4 40997.6 41053.2 41069.2 41086.0 3549.0 3574.5 3586.3 3625.0 3626.7 28212.4 29356.4 31833.2 32629.5 32674.5 2858.7 3174.4 3194.7 3274.0 3421.1 481044.0 483630.0 484620.0 484844.0 484989.0 63806.4 64142.0 64151.0 64202.0 64326.7 67283.7 67305.0 67323.3 67381.7 67426.0 15210.8 16706.8 16881.6 16894.2 17031.1 11651.7 12152.0 12937.3 12947.8 12952.5 17328.7 17779.0 17787.6 17907.4 17930.6 5785.7 6388.5 7378.4 7382.4 7398.6 911.2 1005.9 1054.0 1101.8 1125.6 27305.0 29056.0 31344.4 31594.8 32584.9 882.9 1106.8 1106.9 1128.7 1201.1 16274.6 16638.8 17080.0 17118.2 17255.4 669882.0 672852.0 675209.0 677432.0 681568.0 61515.7 61529.1 61705.7 61709.7 61761.7 1284.1 1414.8 1432.5 1444.5 1475.6 29113.9 31382.5 31482.2 31635.8 32298.0 51129.5 51601.0 51741.5 51868.7 51875.7 562464.0 564300.4 564562.0 566599.0 569917.0 22925.1 23185.9 25849.6 25894.4 27821.3 47488.8 48413.5 48509.5 48875.8 49475.0 38612.0 38647.4 39005.4 39894.2 40075.2 38425.8 39436.0 39533.6 39945.8 40650.0 39307.6 39891.0 40273.0 40544.6 40568.6 28957.1 32300.7 34594.1 35600.1 35733.8 24667.0 24750.6 26080.6 26105.2 26608.2 04:49:32 INFO - PROCESS | 1677 | 04:49:32 INFO - PROCESS | 1677 | [#2] jquery.html Cycles:180 Average:33998.61 Median:14280.45 stddev:94344.73 (660.7%) stddev-sans-first:94575.62 04:49:32 INFO - PROCESS | 1677 | Values: 376.5 383.6 386.2 403.5 412.5 19763.0 21061.4 21360.8 21396.7 22107.7 20970.5 21087.7 22054.1 22079.2 22113.0 5267.6 5765.7 5943.4 6159.9 6170.7 4241.1 4470.0 4479.4 5998.5 6558.0 6721.8 6828.5 6842.7 6843.1 6866.1 6575.5 6755.3 6758.4 6805.4 6831.3 3685.2 5511.7 5877.5 5881.6 5918.5 20170.7 25450.9 25509.6 25816.4 25857.6 2234.5 2269.3 2472.5 2473.2 2703.3 13296.3 15275.6 15281.5 15301.8 15995.6 1840.5 1877.9 1918.4 2011.9 2024.6 104974.5 109317.5 109358.8 109766.0 111164.9 10012.3 10172.8 10838.8 11354.1 11408.4 13941.7 14024.6 14049.8 14055.3 14056.2 8621.0 8834.5 8855.7 8929.9 8947.8 8892.4 8895.6 8917.1 9037.1 9045.4 6644.1 6769.3 6774.7 6801.7 7056.4 1724.3 1757.6 1819.7 1853.5 1873.7 852.4 926.8 953.3 976.4 1056.1 16965.9 20732.6 22015.6 22196.2 22315.9 966.3 966.6 977.6 980.7 1018.9 11593.4 11866.0 12006.4 12009.6 12040.0 476253.0 500593.0 601168.0 609886.0 610548.0 134586.3 136089.0 141599.0 145569.0 147240.0 922.0 950.9 968.7 990.5 992.9 18041.5 18504.2 19097.0 19353.9 19800.8 43898.0 47254.5 50431.5 51062.5 52554.7 70730.3 70898.6 72889.3 73051.3 74302.3 7748.7 8006.7 8032.4 8091.5 8245.6 8839.4 11467.8 12215.7 12440.0 12522.1 8526.7 10617.9 10810.4 10904.0 11178.4 9701.8 10636.5 10661.7 10796.9 10919.2 8624.3 11042.6 11228.9 11233.8 11234.8 7907.8 8077.3 8083.2 8094.9 8213.5 9644.4 10979.8 11060.7 11130.7 11144.3 04:49:32 INFO - PROCESS | 1677 | 04:49:32 INFO - PROCESS | 1677 | [#3] mootools.html Cycles:180 Average:5592.69 Median:4778.75 stddev:3142.43 (65.8%) stddev-sans-first:3135.14 04:49:32 INFO - PROCESS | 1677 | Values: 1360.0 1519.3 1554.3 1757.3 1940.9 7721.9 8499.7 8628.7 8637.3 8831.8 8344.7 8593.8 8600.4 8602.7 8611.9 2093.6 2297.2 2341.7 2366.1 2378.9 2377.9 2399.0 2406.6 2408.0 2409.1 2331.8 2355.3 2369.6 2370.9 2371.0 2079.2 2270.7 2321.0 2338.5 2342.2 2363.1 2388.5 2390.8 2392.7 2395.0 7373.2 8606.4 8606.4 8683.4 8688.0 4262.0 5064.8 5271.8 5279.9 5286.3 3448.6 3538.5 3606.0 3609.3 3639.0 1000.3 1042.5 1046.5 1050.3 1055.0 11465.9 11476.5 11481.7 11497.3 11515.3 4589.4 4628.8 4755.3 4771.3 4771.6 9717.3 9717.5 9725.2 9726.7 9729.0 5202.8 5300.1 5302.4 5302.6 5303.8 4410.5 4558.6 4643.5 4840.7 4855.3 3845.5 3926.6 4025.4 4060.4 4073.7 1105.7 1256.5 1278.6 1285.1 1301.8 915.2 938.2 940.7 941.2 1007.4 5682.3 5691.3 5780.8 5797.6 5921.1 436.8 445.2 446.1 446.5 465.9 3888.1 3962.9 4002.5 4019.8 4026.7 10072.7 10406.9 10564.1 10570.9 10582.1 10393.8 10431.1 10509.8 10513.6 10525.6 7693.6 7866.8 8019.4 8026.0 8026.7 4867.8 5703.3 5897.4 5960.7 6115.1 9342.6 9865.2 9875.5 9891.0 9908.6 9681.6 9931.1 10000.3 10115.9 10126.6 7252.9 7284.2 7402.9 7448.1 7508.0 8123.8 8134.5 8222.5 8230.2 8242.5 7026.9 7335.5 7340.0 7415.4 7475.3 7070.5 7125.4 7133.1 7266.7 7267.2 6838.6 6851.3 6895.5 6896.7 6925.4 4785.9 4915.2 4940.4 4968.4 5016.7 4237.7 4288.8 4297.8 4925.0 4982.7 04:49:32 INFO - PROCESS | 1677 | 04:49:32 INFO - PROCESS | 1677 | [#4] prototype.html Cycles:180 Average:4243.34 Median:4600.54 stddev:1333.63 (29.0%) stddev-sans-first:1303.93 04:49:32 INFO - PROCESS | 1677 | Values: 289.4 312.0 315.8 316.7 321.5 5132.2 5132.6 5152.5 5177.0 5186.6 3994.4 4695.5 5126.9 5127.9 5161.4 3677.5 3815.4 3861.3 3878.9 3881.3 3726.9 3804.4 3852.5 3908.0 3946.6 3387.6 3749.0 3913.9 3927.7 3933.9 3882.4 3904.2 3909.9 3944.0 4070.5 2866.9 3194.0 3290.3 3345.7 3452.6 4485.3 4927.7 4931.3 4937.9 4968.2 4097.6 4201.0 4449.3 4467.6 4486.8 4571.2 5009.8 5366.5 5401.7 5435.3 3548.8 3706.2 3708.7 3711.8 3722.7 5296.1 5517.8 5537.0 5640.4 5678.8 2839.6 3396.9 3611.9 3618.8 3622.9 3488.5 3626.9 3778.0 3785.9 3809.7 4224.8 4379.9 4383.5 4385.2 4449.3 4768.4 5081.5 5280.9 5432.0 5435.7 1152.6 1156.0 1265.2 1284.5 1294.2 749.8 779.7 790.0 790.8 792.5 3798.7 4061.4 4268.7 4290.7 4291.9 3952.9 4314.0 4325.5 4333.6 4368.6 3772.8 4007.7 4020.8 4242.9 4283.4 2113.4 2372.6 2466.4 2470.5 2471.9 4301.6 4818.9 4833.4 4846.9 4868.9 4004.3 4629.9 4752.7 4767.9 4776.5 4648.0 4829.0 5153.7 5471.8 5824.4 4784.4 5255.7 5642.5 5652.7 5655.6 4791.2 5601.0 5626.4 5626.6 5630.7 5496.0 5804.3 5827.9 5898.1 5902.9 3324.4 3710.9 3747.6 3755.6 3757.0 5323.2 5520.0 5584.2 5590.4 5591.2 4878.3 5424.3 5486.5 5487.4 5515.9 5387.8 5423.6 5423.9 5437.7 5540.3 4103.7 4714.9 5314.5 5323.6 5343.9 5177.5 5438.6 5451.3 5459.5 5462.8 5114.5 5131.6 5345.3 5361.6 5366.3 04:49:32 INFO - PROCESS | 1677 | 04:49:32 INFO - PROCESS | 1677 | [#5] yui.html Cycles:180 Average:23652.49 Median:4455.94 stddev:37285.84 (836.8%) stddev-sans-first:37350.62 04:49:32 INFO - PROCESS | 1677 | Values: 703.9 729.0 761.3 803.2 827.5 15341.9 15458.1 15722.8 15742.5 15745.6 14174.3 14183.3 14249.5 14260.6 14260.9 692.4 711.2 864.8 868.0 893.1 880.9 884.1 891.3 893.8 894.6 938.0 940.1 940.9 946.2 947.3 732.7 786.3 915.1 934.6 942.2 661.9 663.3 664.0 664.4 668.9 14624.9 14672.2 14689.3 14691.9 14700.0 13495.3 13833.0 13845.0 13881.9 14019.4 12662.9 12762.9 12855.2 12927.3 13282.0 4425.8 4486.0 4575.6 4662.1 4700.4 88567.0 88803.0 88907.6 89100.0 89153.8 9254.6 9975.1 10209.5 10376.7 11510.9 21329.8 21597.9 21661.9 21666.6 21680.9 7397.9 7917.1 7922.2 7991.5 8230.3 4314.5 4381.3 4388.4 4390.7 4575.0 3360.5 3499.3 3500.4 3502.9 3507.9 629.1 648.3 652.9 665.4 709.7 573.9 673.2 685.3 685.9 692.4 9746.8 9869.3 9908.0 9934.6 9979.3 552.1 588.3 693.6 701.7 711.7 4487.6 4758.9 4768.2 4770.5 4782.7 97557.0 117267.4 153132.5 153209.0 157174.2 104051.5 128555.0 129929.4 129995.0 131312.3 93460.5 111002.4 112440.0 112573.4 112983.0 8481.5 9122.8 9156.2 9259.0 9282.3 57236.0 60926.7 61970.7 62334.7 63367.4 86606.0 92619.0 96117.5 96842.0 97360.0 11759.9 11965.4 12134.2 12156.0 12203.8 13335.7 13494.7 13511.7 13684.5 13870.5 12313.1 12549.5 12651.0 12981.5 13071.3 12410.9 12588.2 12816.6 12886.8 13028.7 12122.0 12175.9 12388.4 12447.8 12648.2 10400.8 10625.0 10724.4 10742.4 10969.0 10148.0 10486.4 10548.0 10570.3 10590.8 04:49:32 INFO - PROCESS | 1677 | -------- Summary: end -------- 04:49:32 INFO - PROCESS | 1677 | 04:49:32 INFO - TEST-INFO | 1677: exit 0 04:49:32 INFO - TEST-OK | dromaeo_css | took 1116963ms 04:49:32 INFO - TEST-START | kraken 04:49:32 INFO - operating with platform_type : mac_ 04:49:32 INFO - Initialising browser for kraken test... 04:49:32 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/tmpUTp047/profile http://localhost:49276/getInfo.html) 04:49:35 INFO - PROCESS | 1682 | __metrics Screen width/height:1600/1200 04:49:35 INFO - PROCESS | 1682 | colorDepth:24 04:49:35 INFO - PROCESS | 1682 | Browser inner width/height: 1024/689 04:49:35 INFO - PROCESS | 1682 | __metrics 04:49:35 INFO - PROCESS | 1682 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:49:35 ERROR - PROCESS | 1682 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:49:36 INFO - TEST-INFO | 1682: exit 0 04:49:36 INFO - Browser initialized. 04:49:36 INFO - Running cycle 1/1 for kraken test... 04:49:36 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/tmpUTp047/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:49:48 INFO - PROCESS | 1683 | RSS: Main: 242327552 04:49:48 INFO - PROCESS | 1683 | 04:49:50 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 04:49:51 INFO - PROCESS | 1683 | RSS: Main: 291655680 04:49:51 INFO - PROCESS | 1683 | 04:49:54 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 04:49:54 INFO - PROCESS | 1683 | RSS: Main: 302268416 04:49:54 INFO - PROCESS | 1683 | 04:49:58 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 04:49:59 INFO - PROCESS | 1683 | RSS: Main: 386596864 04:49:59 INFO - PROCESS | 1683 | 04:50:01 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 04:50:01 INFO - PROCESS | 1683 | RSS: Main: 288325632 04:50:01 INFO - PROCESS | 1683 | 04:50:03 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:50:04 INFO - PROCESS | 1683 | RSS: Main: 292405248 04:50:04 INFO - PROCESS | 1683 | 04:50:07 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 04:50:07 INFO - PROCESS | 1683 | RSS: Main: 329154560 04:50:07 INFO - PROCESS | 1683 | 04:50:10 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 04:50:11 INFO - PROCESS | 1683 | RSS: Main: 329691136 04:50:11 INFO - PROCESS | 1683 | 04:50:14 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 04:50:14 INFO - PROCESS | 1683 | RSS: Main: 327655424 04:50:14 INFO - PROCESS | 1683 | 04:50:16 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:50:16 INFO - PROCESS | 1683 | RSS: Main: 309837824 04:50:16 INFO - PROCESS | 1683 | 04:50:18 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:50:19 INFO - PROCESS | 1683 | RSS: Main: 316895232 04:50:19 INFO - PROCESS | 1683 | 04:50:21 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:50:22 INFO - PROCESS | 1683 | RSS: Main: 320069632 04:50:22 INFO - PROCESS | 1683 | 04:50:25 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:50:25 INFO - PROCESS | 1683 | RSS: Main: 322564096 04:50:25 INFO - PROCESS | 1683 | 04:50:28 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:50:29 INFO - PROCESS | 1683 | RSS: Main: 323526656 04:50:29 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 04:50:31 INFO - PROCESS | 1683 | RSS: Main: 367603712 04:50:31 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | __start_tp_report 04:50:31 INFO - PROCESS | 1683 | _x_x_mozilla_page_load 04:50:31 INFO - PROCESS | 1683 | _x_x_mozilla_page_load_details 04:50:31 INFO - PROCESS | 1683 | |i|pagename|runs| 04:50:31 INFO - PROCESS | 1683 | |0;ai-astar;112;106;112;112;112;112;113;111;112;110 04:50:31 INFO - PROCESS | 1683 | |1;audio-beat-detection;153;117;114;167;119;116;140;133;156;140 04:50:31 INFO - PROCESS | 1683 | |2;audio-dft;182;184;183;183;182;180;183;183;183;183 04:50:31 INFO - PROCESS | 1683 | |3;audio-fft;79;75;58;86;58;57;62;62;94;61 04:50:31 INFO - PROCESS | 1683 | |4;audio-oscillator;84;92;92;72;72;69;97;95;101;139 04:50:31 INFO - PROCESS | 1683 | |5;imaging-gaussian-blur;87;83;86;84;86;83;86;83;90;85 04:50:31 INFO - PROCESS | 1683 | |6;imaging-darkroom;92;92;92;93;92;93;93;92;92;92 04:50:31 INFO - PROCESS | 1683 | |7;imaging-desaturate;85;86;85;80;84;85;86;85;106;141 04:50:31 INFO - PROCESS | 1683 | |8;json-parse-financial;55;53;52;54;54;54;53;55;54;54 04:50:31 INFO - PROCESS | 1683 | |9;json-stringify-tinderbox;66;74;72;77;72;95;103;104;76;73 04:50:31 INFO - PROCESS | 1683 | |10;stanford-crypto-aes;87;84;87;86;86;100;108;86;124;87 04:50:31 INFO - PROCESS | 1683 | |11;stanford-crypto-ccm;143;137;140;137;136;142;135;146;162;146 04:50:31 INFO - PROCESS | 1683 | |12;stanford-crypto-pbkdf2;164;165;166;165;165;163;164;165;164;163 04:50:31 INFO - PROCESS | 1683 | |13;stanford-crypto-sha256-iterative;66;68;67;67;66;66;65;65;66;67 04:50:31 INFO - PROCESS | 1683 | __end_tp_report 04:50:31 INFO - PROCESS | 1683 | __start_cc_report 04:50:31 INFO - PROCESS | 1683 | _x_x_mozilla_cycle_collect,1927 04:50:31 INFO - PROCESS | 1683 | __end_cc_report 04:50:31 INFO - PROCESS | 1683 | __startTimestamp1464954631310__endTimestamp 04:50:31 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | ------- Summary: start ------- 04:50:31 INFO - PROCESS | 1683 | Number of tests: 14 04:50:31 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:1.99 (1.8%) stddev-sans-first:2.09 04:50:31 INFO - PROCESS | 1683 | Values: 112.0 106.0 112.0 112.0 112.0 112.0 113.0 111.0 112.0 110.0 04:50:31 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | [#1] audio-beat-detection Cycles:10 Average:135.50 Median:140.00 stddev:18.92 (13.5%) stddev-sans-first:18.98 04:50:31 INFO - PROCESS | 1683 | Values: 153.0 117.0 114.0 167.0 119.0 116.0 140.0 133.0 156.0 140.0 04:50:31 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.00 stddev:1.07 (0.6%) stddev-sans-first:1.12 04:50:31 INFO - PROCESS | 1683 | Values: 182.0 184.0 183.0 183.0 182.0 180.0 183.0 183.0 183.0 183.0 04:50:31 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | [#3] audio-fft Cycles:10 Average:69.20 Median:68.50 stddev:13.32 (19.5%) stddev-sans-first:13.65 04:50:31 INFO - PROCESS | 1683 | Values: 79.0 75.0 58.0 86.0 58.0 57.0 62.0 62.0 94.0 61.0 04:50:31 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:88.00 stddev:20.31 (23.1%) stddev-sans-first:21.37 04:50:31 INFO - PROCESS | 1683 | Values: 84.0 92.0 92.0 72.0 72.0 69.0 97.0 95.0 101.0 139.0 04:50:31 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.00 stddev:2.21 (2.6%) stddev-sans-first:2.26 04:50:31 INFO - PROCESS | 1683 | Values: 87.0 83.0 86.0 84.0 86.0 83.0 86.0 83.0 90.0 85.0 04:50:31 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | [#6] imaging-darkroom Cycles:10 Average:92.30 Median:92.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 04:50:31 INFO - PROCESS | 1683 | Values: 92.0 92.0 92.0 93.0 92.0 93.0 93.0 92.0 92.0 92.0 04:50:31 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | [#7] imaging-desaturate Cycles:10 Average:92.30 Median:85.00 stddev:18.48 (21.7%) stddev-sans-first:19.41 04:50:31 INFO - PROCESS | 1683 | Values: 85.0 86.0 85.0 80.0 84.0 85.0 86.0 85.0 106.0 141.0 04:50:31 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:0.92 (1.7%) stddev-sans-first:0.87 04:50:31 INFO - PROCESS | 1683 | Values: 55.0 53.0 52.0 54.0 54.0 54.0 53.0 55.0 54.0 54.0 04:50:31 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | [#9] json-stringify-tinderbox Cycles:10 Average:81.20 Median:73.50 stddev:13.94 (19.0%) stddev-sans-first:13.66 04:50:31 INFO - PROCESS | 1683 | Values: 66.0 74.0 72.0 77.0 72.0 95.0 103.0 104.0 76.0 73.0 04:50:31 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | [#10] stanford-crypto-aes Cycles:10 Average:93.50 Median:86.00 stddev:13.20 (15.4%) stddev-sans-first:13.79 04:50:31 INFO - PROCESS | 1683 | Values: 87.0 84.0 87.0 86.0 86.0 100.0 108.0 86.0 124.0 87.0 04:50:31 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | [#11] stanford-crypto-ccm Cycles:10 Average:142.40 Median:142.50 stddev:7.96 (5.6%) stddev-sans-first:8.44 04:50:31 INFO - PROCESS | 1683 | Values: 143.0 137.0 140.0 137.0 136.0 142.0 135.0 146.0 162.0 146.0 04:50:31 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.40 Median:165.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 04:50:31 INFO - PROCESS | 1683 | Values: 164.0 165.0 166.0 165.0 165.0 163.0 164.0 165.0 164.0 163.0 04:50:31 INFO - PROCESS | 1683 | 04:50:31 INFO - PROCESS | 1683 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:0.95 (1.4%) stddev-sans-first:1.00 04:50:31 INFO - PROCESS | 1683 | Values: 66.0 68.0 67.0 67.0 66.0 66.0 65.0 65.0 66.0 67.0 04:50:31 INFO - PROCESS | 1683 | -------- Summary: end -------- 04:50:31 INFO - PROCESS | 1683 | 04:50:32 INFO - TEST-INFO | 1683: exit 0 04:50:32 INFO - TEST-OK | kraken | took 59383ms 04:50:32 INFO - SUITE-END | took 1176s 04:50:32 INFO - Completed test suite (00:19:36) 04:50:32 INFO - javascript benchmark 04:50:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3420.8289585520724, 3445.620981692012, 3453.844576994035, 3457.965420345796, 3510.80690562019, 5534.225208470817, 5545.473180047899, 5623.606347555673, 5633.746481408341, 5678.630888992213, 5577.414371490996, 5595.020871347519, 5710.428957104287, 5711.914321285184, 5765.711714414283, 5049.570786483153, 5052.191649336107, 5121.206213036985, 5149.0731668299695, 5209.426963034082, 5196.038732834419, 5228.686278823283, 5236.685798852062, 5259.027079990195, 5261.973690131563, 5037.798488060492, 5066.670666406671, 5087.9745601272125, 5109.335786347789, 5136.845894623168, 5144.996725638502, 5152.768125434364, 5158.123119069767, 5158.535731784157, 5180.248863914749, 5081.517255860687, 5106.770195341219, 5123.462036486152, 5134.101532231882, 5151.4333423323405, 5525.254090697785, 5525.64083334586, 5585.664860108366, 5608.831735047955, 5650.74571644277, 6084.739250410383, 6104.145419641254, 6129.38706129388, 6173.81478555644, 6191.288001879797, 6181.752729890799, 6214.533909956771, 6248.1564988726395, 6251.562390632657, 6264.029075493306, 5605.159226116074, 5638.464345887134, 5720.771169153188, 5729.25519682447, 5808.883822323614, 6343.238811342576, 6380.904286435707, 6393.840153996187, 6398.9040164397575, 6415.550911436155, 6093.472981630176, 6094.235304088621, 6094.476380904774, 6099.9137557143285, 6111.113888486145, 4991.80032798684, 6070.757169713162, 6124.755009799647, 6137.223770008841, 6139.8601398601395, 6105.64170830808, 6122.215193971453, 6130.275842464154, 6136.519078024463, 6140.749659041455, 6089.727580591997, 6145.647957449354, 6153.7230824613, 6157.599756015845, 6157.96921015392, 4793.9280910786365, 4834.854954351375, 4850.199717046718, 4884.1941079722155, 4936.333594964705, 4364.367166760865, 4372.650187984954, 4382.474103107584, 4386.232409328419, 4438.001449673798, 5611.410801865827, 5628.71856407178, 5631.267935168402, 5641.210230567724, 5686.516646085046, 5966.731497082642, 6057.091436284592, 6076.8784624308155, 6098.099428985345, 6237.126802247598, 5509.00837849191, 5550.306211723535, 5563.276774019432, 5567.359753628303, 5573.052581061229, 5298.6290959633225, 5302.549283310885, 5307.708076055854, 5323.30796996388, 5361.249425080492, 6336.841578960563, 6350.587211831216, 6350.714217860207, 6377.23473183221, 6378.808635740936, 6179.62922224668, 6257.78097766576, 6259.7496100157305, 6267.749290028348, 6270.216222972129, 6252.718627661675, 6299.811005669837, 6361.268454127835, 6362.554621176659, 6371.267304260069, 6127.403330890726, 6133.731528139169, 6138.878900475605, 6144.907826382519, 6148.877022459616, 6214.720337584729, 6235.875282494416, 6241.375862413723, 6242.188515492957, 6252.437280644764, 5798.449147330997, 5865.794697185578, 5866.5893387462465, 5890.440408161217, 5969.582129250911, 5884.558658100574, 5917.319508256605, 5987.850303742267, 5994.790182343771, 6098.29869564988, 5890.676012819336, 5899.970500147472, 5903.409659034234, 5955.67243801595, 5971.373005834324, 6009.2188015557695, 6027.78902738402, 6046.969765151146, 6059.939510585542, 6088.543359248163, 5727.5417966561745, 5807.680577568274, 5809.912851307149, 5876.882462350814, 5883.852903677443], "value": 5643.834271840732, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.6402697976518, 504.96482211466486, 508.66173994293797, 520.7786690656473, 552.3840917377125, 30460.695393046062, 41988.37017444737, 42472.0, 42491.57508424915, 42551.14897702044, 43961.56038439614, 44044.0, 44368.55631443684, 44430.55569444304, 44537.55462445375, 7360.0, 9440.291978101635, 9644.22846172308, 9650.71047868565, 9675.854862177073, 8632.611532481053, 11672.883271167308, 11903.880961190363, 11915.642530724048, 12194.573189938354, 11825.0, 11915.7616847663, 11924.463399147016, 11926.522939082426, 11926.986206172442, 8613.397062205648, 11629.011534019619, 11749.7650046999, 11893.226940248856, 11941.044716422708, 11713.94143029288, 11851.822222666668, 11860.881391186063, 11860.881391186063, 11870.287782732978, 40471.392929106085, 40997.59002409968, 41053.178936421406, 41069.1786164275, 41086.0, 3548.964510354889, 3574.535310409656, 3586.264815712773, 3624.9818750906074, 3626.7279954003557, 28212.448315342648, 29356.412871742446, 31833.204169895707, 32629.51055734166, 32674.509882351784, 2858.6712528059334, 3174.380995705815, 3194.712475877159, 3273.967260327414, 3421.1105112670634, 481044.0, 483630.0, 484620.0, 484844.0, 484989.0, 63806.36193638097, 64142.0, 64151.0, 64202.0, 64326.67836660787, 67283.66358168177, 67305.0, 67323.32676673269, 67381.66309168423, 67426.0, 15210.847891520943, 16706.83293167077, 16881.57796055084, 16894.239759210865, 17031.063291519087, 11651.708707282385, 12152.027837772957, 12937.288449135387, 12947.805782913263, 12952.546660866825, 17328.653426931643, 17779.0, 17787.644247114986, 17907.37324193647, 17930.55173620644, 5785.710714464344, 6388.5182575496465, 7378.409727221917, 7382.409407247407, 7398.593077380688, 911.1935936696071, 1005.939643621385, 1053.951823594435, 1101.8126918423727, 1125.5722825326325, 27305.0, 29055.983040593477, 31344.37311253762, 31594.842025789723, 32584.85952991634, 882.9072947340566, 1106.8063088959561, 1106.8671759388765, 1128.6557599931934, 1201.0751721174647, 16274.593135171715, 16638.83361166373, 17080.0, 17118.229679664444, 17255.39606113805, 669882.0, 672852.0, 675209.0, 677432.0, 681568.0, 61515.692421537606, 61529.077063843186, 61705.691471542355, 61709.69145154246, 61761.69119154375, 1284.13321008319, 1414.7736362181795, 1432.5201057645597, 1444.4944269505588, 1475.5942115918208, 29113.85443072771, 31382.529262061544, 31482.212944676565, 31635.84182079075, 32298.0, 51129.48870511396, 51601.0, 51741.48258517367, 51868.740656296475, 51875.74062129665, 562464.0, 564300.3569964413, 564562.0, 566599.0, 569917.0, 22925.082996679947, 23185.884070580214, 25849.612255815802, 25894.352641183366, 27821.30446738767, 47488.762556187, 48413.5158648409, 48509.5149048505, 48875.755621221666, 49475.0, 38612.0, 38647.42028869513, 39005.41491877567, 39894.2021159581, 40075.198496029334, 38425.80787096047, 39436.014099647735, 39533.604663954146, 39945.80027099846, 40650.0, 39307.6069239304, 39891.00272493211, 40273.0, 40544.594554054085, 40568.594314056485, 28957.131286061453, 32300.67699322977, 34594.135146621535, 35600.10999724924, 35733.82133089318, 24667.0, 24750.628740569267, 26080.608790867773, 26105.216843494727, 26608.20175394741], "value": 20678.556325227823, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.5048960616789, 383.5608228578278, 386.1851493349034, 403.47145239735966, 412.48027485368425, 19763.0, 21061.368158955254, 21360.825154616472, 21396.679049814265, 22107.668384974237, 20970.475738106517, 21087.68368474474, 22054.117835286608, 22079.227227047057, 22113.0, 5267.604929630274, 5765.711714414273, 5943.375945525722, 6159.938400615982, 6170.7223174957235, 4241.130568233505, 4469.955300447003, 4479.3952816369865, 5998.520118390518, 6557.967210163943, 6721.831954201135, 6828.52200345976, 6842.726290948356, 6843.110395648584, 6866.141732283465, 6575.473962083021, 6755.256921738604, 6758.425533829631, 6805.387515123638, 6831.282715314895, 3685.207680348738, 5511.669299842022, 5877.529797616223, 5881.588288819784, 5918.496927761145, 20170.69743953842, 25450.872745636338, 25509.61735573968, 25816.354591135183, 25857.612135817977, 2234.4637287051155, 2269.2851751698163, 2472.505498900209, 2473.208573256559, 2703.2836298380944, 13296.335183240799, 15275.61810954735, 15281.541553753294, 15301.846981530265, 15995.600109997227, 1840.484664294, 1877.9248830046918, 1918.4054190214358, 2011.9195232190696, 2024.645687004783, 104974.47512762464, 109317.45341273323, 109358.81282374388, 109766.0, 111164.88835111626, 10012.349197302152, 10172.796544069226, 10838.837417438745, 11354.091672666333, 11408.429578521042, 13941.651458713613, 14024.579262622137, 14049.78925316121, 14055.297235138201, 14056.22690751998, 8620.95689521561, 8834.469931804113, 8855.73432797017, 8929.910700892908, 8947.821043579223, 8892.421992570462, 8895.599698013604, 8917.063708310663, 9037.057032737994, 9045.366824322235, 6644.136262285969, 6769.32306769329, 6774.661266936633, 6801.727930882808, 7056.400205982446, 1724.3102758896541, 1757.6221112460883, 1819.736138259945, 1853.4717605482642, 1873.6627407066858, 852.3692467574075, 926.7590426489162, 953.2897990996752, 976.4336684722844, 1056.070657821128, 16965.915170424316, 20732.58534829295, 22015.559688806134, 22196.223132190295, 22315.888420557792, 966.3477152921749, 966.5940305071887, 977.6236149082512, 980.6861804222581, 1018.9286749927434, 11593.420328983517, 11865.986730273751, 12006.439162908766, 12009.63971080869, 12040.0, 476253.0, 500593.0, 601168.0, 609886.0, 610548.0, 134586.32706836404, 136089.0, 141599.0, 145569.0, 147240.0, 921.9585118669676, 950.8526178442353, 968.6609686609771, 990.5294984882123, 992.8830066175552, 18041.458756237334, 18504.1673124707, 19097.0, 19353.903230483756, 19800.801991979897, 43898.0, 47254.527454725016, 50431.495685044145, 51062.489375105775, 52554.73722631362, 70730.29269707443, 70898.64550677214, 72889.27110728825, 73051.26948730445, 74302.25697742954, 7748.690052397841, 8006.7333399346935, 8032.437729358889, 8091.474054186459, 8245.628946697292, 8839.381243312906, 11467.827982580102, 12215.69460763488, 12440.0, 12522.123451358682, 8526.701565445179, 10617.946910265398, 10810.35137891729, 10904.0, 11178.385188814693, 9701.805963880824, 10636.52135653882, 10661.733456663645, 10796.946015269874, 10919.235653504178, 8624.310055195443, 11042.558297668325, 11228.88771112311, 11233.775324493628, 11234.775304493702, 7907.84184316299, 8077.273045425704, 8083.232092951161, 8094.919050809652, 8213.466124701876, 9644.421334719938, 10979.835302470628, 11060.723481912695, 11130.666080017612, 11144.331340119294], "value": 9761.780491683521, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.9936047324982, 1519.2631573686763, 1554.292796777466, 1757.3322137587716, 1940.922363105475, 7721.884171737422, 8499.702510412137, 8628.654853805856, 8637.309015278779, 8831.779205519866, 8344.749657510269, 8593.828123437528, 8600.35497337699, 8602.655893764242, 8611.870821937677, 2093.591749608819, 2297.2304278066867, 2341.672165896776, 2366.148186652804, 2378.916737914173, 2377.8573285602833, 2399.040383846458, 2406.578848701479, 2408.0247499762536, 2409.0604664180983, 2331.7668233176632, 2355.328731311568, 2369.6327069304198, 2370.893309801063, 2370.988145059281, 2079.2202923903537, 2270.67075274086, 2321.025169428844, 2338.5150429477235, 2342.1685301717816, 2363.078399424226, 2388.5103553771437, 2390.8087353011892, 2392.7248366437734, 2394.9941024769464, 7373.225811289791, 8606.35452341077, 8606.44058136219, 8683.435576687496, 8687.956560217222, 4262.0, 5064.797408103708, 5271.815486457956, 5279.894402111936, 5286.259923610698, 3448.5516882805327, 3538.4692296155526, 3605.963940360615, 3609.3322735294055, 3639.0, 1000.2897942460861, 1042.5204405973261, 1046.5447530324257, 1050.2648146297622, 1055.0451841083832, 11465.942670286679, 11476.540938362456, 11481.712957176054, 11497.252678575866, 11515.30908145514, 4589.449266088091, 4628.791704373311, 4755.310479980452, 4771.284307353924, 4771.618270538349, 9717.319787614799, 9717.514124293757, 9725.221982241404, 9726.659566915125, 9728.978457262026, 5202.791888324425, 5300.09898317287, 5302.416734159239, 5302.628815982921, 5303.840884773463, 4410.470743510735, 4558.566936141061, 4643.512431194744, 4840.733759643183, 4855.295982082578, 3845.5000849889334, 3926.6466018058513, 4025.3961905714377, 4060.4112403701297, 4073.6556936210977, 1105.6897576371864, 1256.5036810459817, 1278.6291975327051, 1285.1389568988614, 1301.7656821771982, 915.2494954137676, 938.2025278513346, 940.7177846645978, 941.1576638908139, 1007.4106647611083, 5682.346530149003, 5691.317041954909, 5780.797672081457, 5797.623154494944, 5921.111833224964, 436.79034063649624, 445.18086720434553, 446.09442831053013, 446.5155306492427, 465.855584768716, 3888.1446081861945, 3962.8612998544913, 4002.519697636244, 4019.7789121598007, 4026.6778657707314, 10072.68328284484, 10406.895931040744, 10564.102051325723, 10570.894291056991, 10582.100521455608, 10393.792124157626, 10431.11335536488, 10509.84235236472, 10513.632022879163, 10525.631602893862, 7693.615319234016, 7866.780648999415, 8019.39854510914, 8026.036875574969, 8026.678932842738, 4867.80528778859, 5703.287089113861, 5897.351291357862, 5960.701964901824, 6115.082737589397, 9342.579583918605, 9865.210783137476, 9875.506224688594, 9890.950545247228, 9908.554115064695, 9681.564329605326, 9931.056549627774, 10000.349977251455, 10115.89884101179, 10126.645567405105, 7252.891206631799, 7284.162321333115, 7402.925970740223, 7448.068991376078, 7507.962460187664, 8123.79690507742, 8134.471259368122, 8222.506649601042, 8230.21812927771, 8242.464239824392, 7026.929730702832, 7335.523190992569, 7340.045794046953, 7415.406767458699, 7475.327220550176, 7070.540414873222, 7125.394341481032, 7133.108361454818, 7266.7093316266755, 7267.164276108104, 6838.623875686885, 6851.314868513109, 6895.482838787011, 6896.689648965708, 6925.376716095576, 4785.875319299892, 4915.18899381598, 4940.431850337113, 4968.378952630921, 5016.749162541932, 4237.660987121085, 4288.785560721889, 4297.806598703129, 4925.039617274597, 4982.676126051795], "value": 4434.110713765639, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.3634005188585, 312.01403564735426, 315.84681429506685, 316.7291965369609, 321.50006739520035, 5132.230165475181, 5132.615053870961, 5152.458991805854, 5176.974115129424, 5186.611004174683, 3994.4208089826957, 4695.46002209746, 5126.8720881406125, 5127.923081153776, 5161.406438259602, 3677.4667673187373, 3815.3513902636478, 3861.3435715928217, 3878.8836334909997, 3881.28196283687, 3726.9440958385644, 3804.353259945816, 3852.5184351956004, 3907.960920390802, 3946.6448019678223, 3387.6104248011547, 3749.0, 3913.902152446183, 3927.6857851371824, 3933.9213215735667, 3882.3594106972314, 3904.160605469809, 3909.882703518899, 3944.0, 4070.531888832763, 2866.885324587014, 3194.0, 3290.2925870937624, 3345.7156141727983, 3452.6202117767025, 4485.327200919888, 4927.679700819435, 4931.312434723084, 4937.851864444073, 4968.22992436173, 4097.590240975908, 4200.991761977134, 4449.28811390176, 4467.642588592905, 4486.820527178909, 4571.1658771561515, 5009.774560144766, 5366.517013468807, 5401.731272865367, 5435.320584926884, 3548.840302186408, 3706.2395362203006, 3708.6662200401825, 3711.777293362407, 3722.7021838252876, 5296.126139187029, 5517.806876759294, 5536.99999999996, 5640.379558248589, 5678.829635110953, 2839.616651751987, 3396.8811091611674, 3611.8735844245853, 3618.8371523281517, 3622.8550857965915, 3488.4767284907466, 3626.9455958160647, 3778.0, 3785.9242815144094, 3809.6570609075775, 4224.809883555247, 4379.912401751947, 4383.452068491439, 4385.210662080856, 4449.2658711312915, 4768.427788665383, 5081.4918508148885, 5280.917411930545, 5431.972840135853, 5435.701036442954, 1152.5965911930925, 1155.9884401156048, 1265.234533107475, 1284.48430851596, 1294.242867922276, 749.7563291930145, 779.653054390802, 789.9881501777478, 790.7548659915444, 792.5165648954134, 3798.658120769089, 4061.431399604058, 4268.679849011337, 4290.699651024461, 4291.935620965688, 3952.9209415812097, 4314.0, 4325.545817689161, 4333.566643335705, 4368.628666563314, 3772.8113594320175, 4007.73949693269, 4020.7587544747407, 4242.936355954663, 4283.400323954649, 2113.3554265949033, 2372.632242002492, 2466.445049863766, 2470.493548822487, 2471.938201544976, 4301.569843015736, 4818.879528011757, 4833.3958255218095, 4846.854594362175, 4868.92883565615, 4004.3392840181045, 4629.907401851944, 4752.693009422367, 4767.928481072787, 4776.498467660915, 4647.9767601161775, 4828.95171048292, 5153.6907785532985, 5471.781128754965, 5824.359320474744, 4784.449788274393, 5255.710935898563, 5642.548596112383, 5652.664927692516, 5655.575831812713, 4791.161546729378, 5601.031453329741, 5626.38109807929, 5626.5780066496, 5630.662160270397, 5495.9832431000395, 5804.332501762183, 5827.912581311369, 5898.144769008555, 5902.852428689317, 3324.4016077106353, 3710.8515659373325, 3747.587765345864, 3755.5916531300763, 3757.0, 5323.228131920772, 5520.006398848085, 5584.246126772834, 5590.440955904377, 5591.189277554813, 4878.341423907727, 5424.294841670558, 5486.478784515464, 5487.368952570346, 5515.944840551543, 5387.757550910297, 5423.566114710735, 5423.945760542344, 5437.749317656882, 5540.252065971043, 4103.692223083341, 4714.882127946828, 5314.4951229633125, 5323.574114070942, 5343.946560534345, 5177.534021938043, 5438.646487978269, 5451.3458384994055, 5459.508644222039, 5462.754176062007, 5114.4629813869005, 5131.615128865425, 5345.278387417804, 5361.597880158925, 5366.302380690485], "value": 3821.1604561545914, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.9084918960534, 728.9744858929937, 761.3338328962158, 803.208839293296, 827.5448503323171, 15341.923290383547, 15458.072515649055, 15722.842771572296, 15742.527724168265, 15745.60635984101, 14174.291285435738, 14183.290835458212, 14249.501267455642, 14260.643483912907, 14260.857391426083, 692.4356649330779, 711.2070041903266, 864.7924498120453, 868.0494858130337, 893.1381217125472, 880.8986966498838, 884.1203003012012, 891.3047822698305, 893.8167675626481, 894.575076838503, 937.976550586234, 940.05994005994, 940.92472602192, 946.1957336264189, 947.2564037230775, 732.7325526182958, 786.268770043859, 915.0849150849151, 934.6308208257734, 942.236788201559, 661.8543920337518, 663.2869665109978, 663.9568428052161, 664.3754870421836, 668.8762578922915, 14624.853751462562, 14672.19302938338, 14689.338979745937, 14691.853081469155, 14700.0, 13495.257760823151, 13833.0, 13845.0, 13881.861181388158, 14019.369128389146, 12662.936685316607, 12762.872371276262, 12855.16441431304, 12927.288999105045, 13282.0, 4425.845095421645, 4486.038753426501, 4575.58819706228, 4662.090892275974, 4700.412448443944, 88567.0, 88803.0, 88907.55546222292, 89100.0, 89153.77115572098, 9254.629814807333, 9975.052370024836, 10209.540570674337, 10376.740581485372, 11510.94244528772, 21329.78670213309, 21597.892010539847, 21661.891690541448, 21666.56666866654, 21680.89159554192, 7397.889031664529, 7917.0895347034675, 7922.207779222032, 7991.480553763987, 8230.300424463987, 4314.532401341226, 4381.277089280296, 4388.425280109581, 4390.670699697535, 4575.0392417592675, 3360.479125735515, 3499.3001399720156, 3500.439929611301, 3502.9124271893406, 3507.9298414031578, 629.1129507394552, 648.335456157433, 652.9477641788645, 665.4077870695086, 709.7196607340072, 573.9339975902741, 673.1686367336335, 685.3146853146853, 685.9382655560923, 692.3630260160664, 9746.75633109178, 9869.259805514472, 9907.950460247652, 9934.602615895428, 9979.251556133318, 552.1221258199482, 588.2969851027981, 693.6427739714052, 701.7228194863103, 711.7010855440624, 4487.6185524230805, 4758.857234282977, 4768.165571025057, 4770.475247722748, 4782.736949467812, 97557.0, 117267.41366293284, 153132.46867531183, 153209.0, 157174.21412892864, 104051.47974260231, 128555.0, 129929.35035324763, 129995.0, 131312.3434382822, 93460.53269733608, 111002.44498777455, 112440.0, 112573.43713281381, 112983.0, 8481.533515656698, 9122.817543649222, 9156.17594416479, 9258.95370523143, 9282.257419406566, 57236.0, 60926.69536652289, 61970.69014654898, 62334.68832655808, 63367.366326336145, 86606.0, 92619.0, 96117.5194124053, 96842.0, 97360.0, 11759.88240117622, 11965.401729913643, 12134.150609457252, 12156.0, 12203.816942746043, 13335.733285334432, 13494.662633434242, 13511.662208444868, 13684.52104176349, 13870.514531991332, 12313.13808033465, 12549.49802007946, 12651.0, 12981.545645902348, 13071.346432678138, 12410.937945310216, 12588.244705317711, 12816.55142070023, 12886.806697899352, 13028.674283142997, 12122.0, 12175.939120304341, 12388.442520086437, 12447.751044979232, 12648.17786843889, 10400.791984160123, 10625.0, 10724.410157441416, 10742.40916749555, 10969.012788848722, 10148.0, 10486.423246721504, 10548.0, 10570.312929659547, 10590.8411373831], "value": 7714.339401147422, "unit": "score"}], "name": "dromaeo_css", "value": 7280.525793596682, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 112.0, 112.0, 112.0, 112.0, 113.0, 111.0, 112.0, 110.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 117.0, 114.0, 167.0, 119.0, 116.0, 140.0, 133.0, 156.0, 140.0], "value": 135.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 183.0, 183.0, 182.0, 180.0, 183.0, 183.0, 183.0, 183.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 75.0, 58.0, 86.0, 58.0, 57.0, 62.0, 62.0, 94.0, 61.0], "value": 69.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 92.0, 92.0, 72.0, 72.0, 69.0, 97.0, 95.0, 101.0, 139.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 84.0, 86.0, 83.0, 86.0, 83.0, 90.0, 85.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 93.0, 92.0, 93.0, 93.0, 92.0, 92.0, 92.0], "value": 92.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 80.0, 84.0, 85.0, 86.0, 85.0, 106.0, 141.0], "value": 92.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 52.0, 54.0, 54.0, 54.0, 53.0, 55.0, 54.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 74.0, 72.0, 77.0, 72.0, 95.0, 103.0, 104.0, 76.0, 73.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 86.0, 86.0, 100.0, 108.0, 86.0, 124.0, 87.0], "value": 93.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 137.0, 140.0, 137.0, 136.0, 142.0, 135.0, 146.0, 162.0, 146.0], "value": 142.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 166.0, 165.0, 165.0, 163.0, 164.0, 165.0, 164.0, 163.0], "value": 164.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 67.0, 66.0, 66.0, 65.0, 65.0, 66.0, 67.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1461.3, "alertThreshold": 2.0}]} 04:50:32 INFO - Return code: 0 04:50:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:50:32 ERROR - # TBPL SUCCESS # 04:50:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:50:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:50:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:50:32 INFO - 2016-06-03 04:50:32.632 screenresolution[1687:10360] starting screenresolution argv=screenresolution get 04:50:32 INFO - 2016-06-03 04:50:32.635 screenresolution[1687:10360] Display 0: 1600x1200x32@60 04:50:32 INFO - 2016-06-03 04:50:32.641 screenresolution[1688:10363] starting screenresolution argv=screenresolution list 04:50:32 INFO - Available Modes on Display 0 04:50:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:50:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:50:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:50:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:50:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:50:32 INFO - Intel Iris: 04:50:32 INFO - Chipset Model: Intel Iris 04:50:32 INFO - Type: GPU 04:50:32 INFO - Bus: Built-In 04:50:32 INFO - VRAM (Dynamic, Max): 1536 MB 04:50:32 INFO - Vendor: Intel (0x8086) 04:50:32 INFO - Device ID: 0x0a2e 04:50:32 INFO - Revision ID: 0x0009 04:50:32 INFO - Displays: 04:50:32 INFO - Display: 04:50:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:50:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:50:32 INFO - Display Serial Number: 1600x1200 60 04:50:32 INFO - Main Display: Yes 04:50:32 INFO - Mirror: Off 04:50:32 INFO - Online: Yes 04:50:32 INFO - Rotation: Supported 04:50:32 INFO - Return code: 0 04:50:32 INFO - Running post-action listener: _resource_record_post_action 04:50:32 INFO - ##### 04:50:32 INFO - ##### Finished run-tests step (success) 04:50:32 INFO - ##### 04:50:32 INFO - Running post-run listener: _resource_record_post_run 04:50:32 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 31194624; Write bytes: 697979392; Read time: 196; Write time: 1957 04:50:32 INFO - TinderboxPrint: CPU usage
53.9% 04:50:32 INFO - TinderboxPrint: I/O read bytes / time
31,194,624 / 196 04:50:32 INFO - TinderboxPrint: I/O write bytes / time
697,979,392 / 1,957 04:50:32 INFO - TinderboxPrint: CPU idle
2,237.8 (46.1%) 04:50:32 INFO - TinderboxPrint: CPU system
969.7 (20.0%) 04:50:32 INFO - TinderboxPrint: CPU user
1,642.1 (33.9%) 04:50:32 INFO - TinderboxPrint: Swap in / out
427,175,936 / 0 04:50:32 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 399905280; Write bytes: 394565632; Read time: 30010; Write time: 851 04:50:32 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 29363712; Write bytes: 294234624; Read time: 177; Write time: 1077 04:50:33 INFO - Running post-run listener: _upload_blobber_files 04:50:33 INFO - Blob upload gear active. 04:50:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:50:33 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 04:50:33 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'] 04:50:33 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 04:50:33 INFO - (blobuploader) - INFO - Open directory for files ... 04:50:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:50:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:50:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:34 INFO - (blobuploader) - INFO - Done attempting. 04:50:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:50:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:50:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:35 INFO - (blobuploader) - INFO - Done attempting. 04:50:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:50:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:50:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:37 INFO - (blobuploader) - INFO - Done attempting. 04:50:37 INFO - (blobuploader) - INFO - Iteration through files over. 04:50:37 INFO - Return code: 0 04:50:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:50:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:50:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b672d2ca656948c1853e021fae74987a2d00c2b31f09fb303fe0a58d1916e86e8b4d0e74f5947705d2be39afe4f8fd9f90cf97ec763ddae255151d2660bd2e83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b1e21659e2e9f566eb0d9a54b5ca4e0b8b6c76c3d1cadb6f34647d65c14d0edaae2b542a63eedcfebbb47efc2f4d10e84f6607fd9906b5fb8eca2d0ce09be16c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/696e1f5ccee7b8ef19ae19ec8a477f18709c01ca81ba8d9b58d551d127014a7147944c93c2cf31bd21ce01665ac4ed0e911c8b5f5a1a1015140e15f6bd825618"} 04:50:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:50:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:50:37 INFO - Contents: 04:50:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b672d2ca656948c1853e021fae74987a2d00c2b31f09fb303fe0a58d1916e86e8b4d0e74f5947705d2be39afe4f8fd9f90cf97ec763ddae255151d2660bd2e83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b1e21659e2e9f566eb0d9a54b5ca4e0b8b6c76c3d1cadb6f34647d65c14d0edaae2b542a63eedcfebbb47efc2f4d10e84f6607fd9906b5fb8eca2d0ce09be16c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/696e1f5ccee7b8ef19ae19ec8a477f18709c01ca81ba8d9b58d551d127014a7147944c93c2cf31bd21ce01665ac4ed0e911c8b5f5a1a1015140e15f6bd825618"} 04:50:37 INFO - Running post-run listener: copy_logs_to_upload_dir 04:50:37 INFO - Copying logs to upload dir... 04:50:37 INFO - mkdir: /builds/slave/test/build/upload/logs 04:50:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.154895 ========= master_lag: 0.64 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-06-03 04:50:38.370582) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 04:50:38.374006) ========= 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.YAmW3xkmSd/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.4TVUoHcw7R/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b672d2ca656948c1853e021fae74987a2d00c2b31f09fb303fe0a58d1916e86e8b4d0e74f5947705d2be39afe4f8fd9f90cf97ec763ddae255151d2660bd2e83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b1e21659e2e9f566eb0d9a54b5ca4e0b8b6c76c3d1cadb6f34647d65c14d0edaae2b542a63eedcfebbb47efc2f4d10e84f6607fd9906b5fb8eca2d0ce09be16c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/696e1f5ccee7b8ef19ae19ec8a477f18709c01ca81ba8d9b58d551d127014a7147944c93c2cf31bd21ce01665ac4ed0e911c8b5f5a1a1015140e15f6bd825618"} build_url:https://queue.taskcluster.net/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011238 build_url: 'https://queue.taskcluster.net/v1/task/WoYv1X-uTR6DBDmc_Zc-gw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b672d2ca656948c1853e021fae74987a2d00c2b31f09fb303fe0a58d1916e86e8b4d0e74f5947705d2be39afe4f8fd9f90cf97ec763ddae255151d2660bd2e83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b1e21659e2e9f566eb0d9a54b5ca4e0b8b6c76c3d1cadb6f34647d65c14d0edaae2b542a63eedcfebbb47efc2f4d10e84f6607fd9906b5fb8eca2d0ce09be16c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/696e1f5ccee7b8ef19ae19ec8a477f18709c01ca81ba8d9b58d551d127014a7147944c93c2cf31bd21ce01665ac4ed0e911c8b5f5a1a1015140e15f6bd825618"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 04:50:38.410279) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:50:38.411223) ========= 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.YAmW3xkmSd/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.4TVUoHcw7R/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005048 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 04:50:38.463433) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 04:50:38.464164) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 04:50:38.464878) ========= ========= Total master_lag: 0.86 =========