builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0116 starttime: 1464612928.82 results: success (0) buildid: 20160530040947 builduid: 60cf48b535454098acfa8bcb2293f5a2 revision: 00dccad34edae14b16de663c884a36ef2ec575b4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 05:55:28.816579) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 05:55:28.817058) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 05:55:28.817333) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 05:55:28.853596) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:55:28.853988) ========= 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.ck6m1LIBZM/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.70RcoQMgB3/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-30 05:55:28-- 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% 9.54M=0.001s 2016-05-30 05:55:28 (9.54 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.078992 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:55:28.953055) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:55:28.953363) ========= 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.ck6m1LIBZM/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.70RcoQMgB3/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.029025 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:55:29.002397) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-30 05:55:29.002763) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 00dccad34edae14b16de663c884a36ef2ec575b4 --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 00dccad34edae14b16de663c884a36ef2ec575b4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ck6m1LIBZM/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.70RcoQMgB3/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-30 05:55:29,071 truncating revision to first 12 chars 2016-05-30 05:55:29,071 Setting DEBUG logging. 2016-05-30 05:55:29,071 attempt 1/10 2016-05-30 05:55:29,071 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/00dccad34eda?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 05:55:31,178 unpacking tar archive at: fx-team-00dccad34eda/testing/mozharness/ program finished with exit code 0 elapsedTime=2.364869 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-30 05:55:31.387010) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 05:55:31.387328) ========= script_repo_revision: 00dccad34edae14b16de663c884a36ef2ec575b4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 05:55:31.387711) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 05:55:31.387986) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 05:55:31.403927) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-30 05:55:31.404232) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ck6m1LIBZM/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.70RcoQMgB3/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:55:31 INFO - MultiFileLogger online at 20160530 05:55:31 in /builds/slave/test 05:55:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:55:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:55:31 INFO - {'append_to_log': False, 05:55:31 INFO - 'base_work_dir': '/builds/slave/test', 05:55:31 INFO - 'blob_upload_branch': 'Fx-Team', 05:55:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:55:31 INFO - 'branch': 'Fx-Team', 05:55:31 INFO - 'buildbot_json_path': 'buildprops.json', 05:55:31 INFO - 'config_files': ('talos/mac_config.py',), 05:55:31 INFO - 'default_actions': ('clobber', 05:55:31 INFO - 'read-buildbot-config', 05:55:31 INFO - 'download-and-extract', 05:55:31 INFO - 'populate-webroot', 05:55:31 INFO - 'create-virtualenv', 05:55:31 INFO - 'install', 05:55:31 INFO - 'run-tests'), 05:55:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:55:31 INFO - 'download_minidump_stackwalk': True, 05:55:31 INFO - 'download_symbols': 'ondemand', 05:55:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:55:31 INFO - 'tooltool.py': '/tools/tooltool.py', 05:55:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:55:31 INFO - '/tools/misc-python/virtualenv.py')}, 05:55:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:55:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:55:31 INFO - 'installer_path': 'installer.exe', 05:55:31 INFO - 'log_level': 'info', 05:55:31 INFO - 'log_name': 'talos', 05:55:31 INFO - 'log_to_console': True, 05:55:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:55:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:55:31 INFO - 'opt_config_files': (), 05:55:31 INFO - 'pip_index': False, 05:55:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:55:31 INFO - 'cmd': ('bash', 05:55:31 INFO - '-c', 05:55:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:55:31 INFO - 'enabled': True, 05:55:31 INFO - 'halt_on_failure': False, 05:55:31 INFO - 'name': 'check_screen_resolution'},), 05:55:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:55:31 INFO - 'cmd': ('bash', 05:55:31 INFO - '-c', 05:55:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:55:31 INFO - 'enabled': True, 05:55:31 INFO - 'halt_on_failure': False, 05:55:31 INFO - 'name': 'check_screen_resolution'},), 05:55:31 INFO - 'run_cmd_checks_enabled': True, 05:55:31 INFO - 'sps_profile': False, 05:55:31 INFO - 'sps_profile_interval': 0, 05:55:31 INFO - 'suite': 'dromaeojs-e10s', 05:55:31 INFO - 'system_bits': '32', 05:55:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:55:31 INFO - 'title': 't-yosemite-r7-0116', 05:55:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:55:31 INFO - 'use_talos_json': True, 05:55:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:55:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:55:31 INFO - 'work_dir': 'build'} 05:55:31 INFO - ##### 05:55:31 INFO - ##### Running clobber step. 05:55:31 INFO - ##### 05:55:31 INFO - Running pre-action listener: _resource_record_pre_action 05:55:31 INFO - Running main action method: clobber 05:55:31 INFO - rmtree: /builds/slave/test/build 05:55:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:55:33 INFO - Running post-action listener: _resource_record_post_action 05:55:33 INFO - ##### 05:55:33 INFO - ##### Finished clobber step (success) 05:55:33 INFO - ##### 05:55:33 INFO - ##### 05:55:33 INFO - ##### Running read-buildbot-config step. 05:55:33 INFO - ##### 05:55:33 INFO - Running pre-action listener: _resource_record_pre_action 05:55:33 INFO - Running main action method: read_buildbot_config 05:55:33 INFO - Using buildbot properties: 05:55:33 INFO - { 05:55:33 INFO - "project": "", 05:55:33 INFO - "product": "firefox", 05:55:33 INFO - "script_repo_revision": "production", 05:55:33 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 05:55:33 INFO - "repository": "", 05:55:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 05:55:33 INFO - "buildid": "20160530040947", 05:55:33 INFO - "pgo_build": "False", 05:55:33 INFO - "basedir": "/builds/slave/test", 05:55:33 INFO - "buildnumber": 688, 05:55:33 INFO - "slavename": "t-yosemite-r7-0116", 05:55:33 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:55:33 INFO - "platform": "yosemite_r7", 05:55:33 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 05:55:33 INFO - "branch": "fx-team", 05:55:33 INFO - "repo_path": "integration/fx-team", 05:55:33 INFO - "revision": "00dccad34edae14b16de663c884a36ef2ec575b4", 05:55:33 INFO - "stage_platform": "macosx64", 05:55:33 INFO - "builduid": "60cf48b535454098acfa8bcb2293f5a2", 05:55:33 INFO - "slavebuilddir": "test" 05:55:33 INFO - } 05:55:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:55:33 INFO - Running post-action listener: _resource_record_post_action 05:55:33 INFO - ##### 05:55:33 INFO - ##### Finished read-buildbot-config step (success) 05:55:33 INFO - ##### 05:55:33 INFO - ##### 05:55:33 INFO - ##### Running download-and-extract step. 05:55:33 INFO - ##### 05:55:33 INFO - Running pre-action listener: _resource_record_pre_action 05:55:33 INFO - Running main action method: download_and_extract 05:55:33 INFO - mkdir: /builds/slave/test/build/tests 05:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:33 INFO - https://queue.taskcluster.net/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:55:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:55:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:55:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/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 05:55:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/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 05:55:34 INFO - Downloaded 1199 bytes. 05:55:34 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:55:34 INFO - Using the following test package requirements: 05:55:34 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:55:34 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:55:34 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:55:34 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:55:34 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:55:34 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:55:34 INFO - u'jsshell-mac.zip'], 05:55:34 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:55:34 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:55:34 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:55:34 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:55:34 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:55:34 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:55:34 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:55:34 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:55:34 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:55:34 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:55:34 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:55:34 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:55:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:34 INFO - https://queue.taskcluster.net/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:55:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:55:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:55:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/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 05:55:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/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 05:55:36 INFO - Downloaded 18685203 bytes. 05:55:36 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'] 05:55:36 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 05:55:39 INFO - Return code: 0 05:55:39 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 05:55:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:39 INFO - https://queue.taskcluster.net/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:55:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:55:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:55:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/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 05:55:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/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 05:55:40 INFO - Downloaded 18685203 bytes. 05:55:40 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'] 05:55:40 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 05:55:43 INFO - Return code: 0 05:55:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:43 INFO - https://queue.taskcluster.net/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:55:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:55:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:55:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/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 05:55:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/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 05:55:45 INFO - Downloaded 10834034 bytes. 05:55:45 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'] 05:55:45 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 05:55:45 INFO - Return code: 0 05:55:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:45 INFO - https://queue.taskcluster.net/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:55:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:55:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:55:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:55:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQ3duhoLSPaWtpaniGU-8g/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 05:55:48 INFO - Downloaded 110492942 bytes. 05:55:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:55:48 INFO - mkdir: /builds/slave/test/properties 05:55:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:55:48 INFO - Writing to file /builds/slave/test/properties/build_url 05:55:48 INFO - Contents: 05:55:48 INFO - build_url:https://queue.taskcluster.net/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:55:50 INFO - Running post-action listener: _resource_record_post_action 05:55:50 INFO - Running post-action listener: set_extra_try_arguments 05:55:50 INFO - ##### 05:55:50 INFO - ##### Finished download-and-extract step (success) 05:55:50 INFO - ##### 05:55:50 INFO - ##### 05:55:50 INFO - ##### Running populate-webroot step. 05:55:50 INFO - ##### 05:55:50 INFO - Running pre-action listener: _resource_record_pre_action 05:55:50 INFO - Running main action method: populate_webroot 05:55:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:55:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:55:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:55:50 INFO - '--tppagecycles', 05:55:50 INFO - '7'], 05:55:50 INFO - 'tests': ['tsvgm']}}, 05:55:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:55:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:55:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:55:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:55:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:55:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:55:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:55:50 INFO - 'talos_options': ['--disable-e10s'], 05:55:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:55:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:55:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:55:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:55:50 INFO - 'talos_options': ['--disable-e10s'], 05:55:50 INFO - 'tests': ['damp', 'tps']}, 05:55:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:55:50 INFO - 'tests': ['damp', 'tps']}, 05:55:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:55:50 INFO - 'tests': ['dromaeo_dom']}, 05:55:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:55:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:55:50 INFO - 'tests': ['a11yr', 05:55:50 INFO - 'ts_paint', 05:55:50 INFO - 'tpaint', 05:55:50 INFO - 'sessionrestore', 05:55:50 INFO - 'sessionrestore_no_auto_restore', 05:55:50 INFO - 'tabpaint']}, 05:55:50 INFO - 'other-e10s': {'tests': ['a11yr', 05:55:50 INFO - 'ts_paint', 05:55:50 INFO - 'tpaint', 05:55:50 INFO - 'sessionrestore', 05:55:50 INFO - 'sessionrestore_no_auto_restore', 05:55:50 INFO - 'tabpaint']}, 05:55:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:55:50 INFO - 'ts_paint', 05:55:50 INFO - 'tpaint', 05:55:50 INFO - 'sessionrestore', 05:55:50 INFO - 'sessionrestore_no_auto_restore', 05:55:50 INFO - 'tabpaint']}, 05:55:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:55:50 INFO - 'ts_paint', 05:55:50 INFO - 'tpaint', 05:55:50 INFO - 'sessionrestore', 05:55:50 INFO - 'sessionrestore_no_auto_restore', 05:55:50 INFO - 'tabpaint']}, 05:55:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:55:50 INFO - 'tests': ['a11yr', 05:55:50 INFO - 'ts_paint', 05:55:50 INFO - 'tpaint', 05:55:50 INFO - 'sessionrestore', 05:55:50 INFO - 'sessionrestore_no_auto_restore', 05:55:50 INFO - 'tabpaint']}, 05:55:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:55:50 INFO - 'tests': ['a11yr', 05:55:50 INFO - 'ts_paint', 05:55:50 INFO - 'tpaint', 05:55:50 INFO - 'sessionrestore', 05:55:50 INFO - 'sessionrestore_no_auto_restore', 05:55:50 INFO - 'tabpaint']}, 05:55:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:55:50 INFO - 'tests': ['tsvgx', 05:55:50 INFO - 'tsvgr_opacity', 05:55:50 INFO - 'tart', 05:55:50 INFO - 'tscrollx', 05:55:50 INFO - 'cart']}, 05:55:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:55:50 INFO - 'tsvgr_opacity', 05:55:50 INFO - 'tart', 05:55:50 INFO - 'tscrollx', 05:55:50 INFO - 'cart']}, 05:55:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:55:50 INFO - 'talos_options': ['--disable-e10s'], 05:55:50 INFO - 'tests': ['tp5o']}, 05:55:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:55:50 INFO - 'tests': ['tp5o']}, 05:55:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:55:50 INFO - 'talos_options': ['--disable-e10s', 05:55:50 INFO - '--xperf_path', 05:55:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:55:50 INFO - 'tests': ['tp5n']}, 05:55:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:55:50 INFO - 'talos_options': ['--xperf_path', 05:55:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:55:50 INFO - 'tests': ['tp5n']}}, 05:55:50 INFO - 'talos.zip': {'path': '', 05:55:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:55:50 INFO - Running post-action listener: _resource_record_post_action 05:55:50 INFO - ##### 05:55:50 INFO - ##### Finished populate-webroot step (success) 05:55:50 INFO - ##### 05:55:50 INFO - ##### 05:55:50 INFO - ##### Running create-virtualenv step. 05:55:50 INFO - ##### 05:55:50 INFO - Running pre-action listener: _resource_record_pre_action 05:55:50 INFO - Running main action method: create_virtualenv 05:55:50 INFO - Creating virtualenv /builds/slave/test/build/venv 05:55:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:55:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:55:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:55:50 INFO - Using real prefix '/tools/python27' 05:55:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:55:50 INFO - Installing distribute.............................................................................................................................................................................................done. 05:55:53 INFO - Installing pip.................done. 05:55:53 INFO - Return code: 0 05:55:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1039fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb235d5e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038a2d30>, '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 0x1036c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103899b20>, '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.ck6m1LIBZM/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.70RcoQMgB3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:55:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:55:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:55:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ck6m1LIBZM/Render', 05:55:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:53 INFO - 'HOME': '/Users/cltbld', 05:55:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:53 INFO - 'LOGNAME': 'cltbld', 05:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:53 INFO - 'MOZ_NO_REMOTE': '1', 05:55:53 INFO - 'NO_EM_RESTART': '1', 05:55:53 INFO - 'PAGER': '/bin/cat', 05:55:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:53 INFO - 'PWD': '/builds/slave/test', 05:55:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:53 INFO - 'SHELL': '/bin/bash', 05:55:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70RcoQMgB3/Listeners', 05:55:53 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:55:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:53 INFO - 'USER': 'cltbld', 05:55:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:53 INFO - 'XPC_FLAGS': '0x0', 05:55:53 INFO - 'XPC_SERVICE_NAME': '0', 05:55:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:54 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:55:54 INFO - Cleaning up... 05:55:54 INFO - Return code: 0 05:55:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:55: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')]} 05:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55: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')]} 05:55:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55: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', '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 0x1039fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb235d5e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038a2d30>, '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 0x1036c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103899b20>, '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.ck6m1LIBZM/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.70RcoQMgB3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:55: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:55: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 psutil>=3.1.1 05:55:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ck6m1LIBZM/Render', 05:55:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:54 INFO - 'HOME': '/Users/cltbld', 05:55:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:54 INFO - 'LOGNAME': 'cltbld', 05:55:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:54 INFO - 'MOZ_NO_REMOTE': '1', 05:55:54 INFO - 'NO_EM_RESTART': '1', 05:55:54 INFO - 'PAGER': '/bin/cat', 05:55:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:54 INFO - 'PWD': '/builds/slave/test', 05:55:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:54 INFO - 'SHELL': '/bin/bash', 05:55:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70RcoQMgB3/Listeners', 05:55:54 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:55:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:54 INFO - 'USER': 'cltbld', 05:55:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:54 INFO - 'XPC_FLAGS': '0x0', 05:55:54 INFO - 'XPC_SERVICE_NAME': '0', 05:55:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:54 INFO - Downloading/unpacking psutil>=3.1.1 05:55: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 05:55: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 05:55: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 05:55: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 05:55: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 05:55: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 05:55:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:55:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:55:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:55:57 INFO - Installing collected packages: psutil 05:55:57 INFO - Running setup.py install for psutil 05:55:57 INFO - building 'psutil._psutil_osx' extension 05:55:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:55:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:55:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:55:58 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:55:58 INFO - building 'psutil._psutil_posix' extension 05:55:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:55:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:55:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:55:58 INFO - ^ 05:55:58 INFO - 1 warning generated. 05:55:58 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:55:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:55:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:55:58 INFO - Successfully installed psutil 05:55:58 INFO - Cleaning up... 05:55:58 INFO - Return code: 0 05:55:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:55:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:58 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 0x1039fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb235d5e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038a2d30>, '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 0x1036c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103899b20>, '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.ck6m1LIBZM/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.70RcoQMgB3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:55:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:55:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:55:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ck6m1LIBZM/Render', 05:55:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:58 INFO - 'HOME': '/Users/cltbld', 05:55:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:58 INFO - 'LOGNAME': 'cltbld', 05:55:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:58 INFO - 'MOZ_NO_REMOTE': '1', 05:55:58 INFO - 'NO_EM_RESTART': '1', 05:55:58 INFO - 'PAGER': '/bin/cat', 05:55:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:58 INFO - 'PWD': '/builds/slave/test', 05:55:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:58 INFO - 'SHELL': '/bin/bash', 05:55:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70RcoQMgB3/Listeners', 05:55:58 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:55:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:58 INFO - 'USER': 'cltbld', 05:55:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:58 INFO - 'XPC_FLAGS': '0x0', 05:55:58 INFO - 'XPC_SERVICE_NAME': '0', 05:55:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:56:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:56:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:56:01 INFO - Installing collected packages: mozsystemmonitor 05:56:01 INFO - Running setup.py install for mozsystemmonitor 05:56:01 INFO - Successfully installed mozsystemmonitor 05:56:01 INFO - Cleaning up... 05:56:01 INFO - Return code: 0 05:56:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:56: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')]} 05:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56: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')]} 05:56:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:01 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 0x1039fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb235d5e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038a2d30>, '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 0x1036c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103899b20>, '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.ck6m1LIBZM/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.70RcoQMgB3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:56:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:56:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:56:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ck6m1LIBZM/Render', 05:56:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:01 INFO - 'HOME': '/Users/cltbld', 05:56:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:01 INFO - 'LOGNAME': 'cltbld', 05:56:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:01 INFO - 'MOZ_NO_REMOTE': '1', 05:56:01 INFO - 'NO_EM_RESTART': '1', 05:56:01 INFO - 'PAGER': '/bin/cat', 05:56:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:01 INFO - 'PWD': '/builds/slave/test', 05:56:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:01 INFO - 'SHELL': '/bin/bash', 05:56:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70RcoQMgB3/Listeners', 05:56:01 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:56:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:01 INFO - 'USER': 'cltbld', 05:56:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:01 INFO - 'XPC_FLAGS': '0x0', 05:56:01 INFO - 'XPC_SERVICE_NAME': '0', 05:56:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:01 INFO - Downloading/unpacking blobuploader==1.2.4 05:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:04 INFO - Downloading blobuploader-1.2.4.tar.gz 05:56:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:56:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:56:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:05 INFO - Downloading docopt-0.6.1.tar.gz 05:56:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:56:05 INFO - Installing collected packages: blobuploader, requests, docopt 05:56:05 INFO - Running setup.py install for blobuploader 05:56:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:56:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:56:05 INFO - Running setup.py install for requests 05:56:06 INFO - Running setup.py install for docopt 05:56:06 INFO - Successfully installed blobuploader requests docopt 05:56:06 INFO - Cleaning up... 05:56:06 INFO - Return code: 0 05:56:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:56: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')]} 05:56:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56: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')]} 05:56:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:06 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 0x1039fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb235d5e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038a2d30>, '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 0x1036c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103899b20>, '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.ck6m1LIBZM/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.70RcoQMgB3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:56:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:56:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:56:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ck6m1LIBZM/Render', 05:56:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:06 INFO - 'HOME': '/Users/cltbld', 05:56:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:06 INFO - 'LOGNAME': 'cltbld', 05:56:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:06 INFO - 'MOZ_NO_REMOTE': '1', 05:56:06 INFO - 'NO_EM_RESTART': '1', 05:56:06 INFO - 'PAGER': '/bin/cat', 05:56:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:06 INFO - 'PWD': '/builds/slave/test', 05:56:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:06 INFO - 'SHELL': '/bin/bash', 05:56:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70RcoQMgB3/Listeners', 05:56:06 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:56:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:06 INFO - 'USER': 'cltbld', 05:56:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:06 INFO - 'XPC_FLAGS': '0x0', 05:56:06 INFO - 'XPC_SERVICE_NAME': '0', 05:56:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:56:06 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-NoHaP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:56:06 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-1iUvQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:56:06 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-mAev9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:56:07 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-QMb0L5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:56:07 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-D6RmgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:56:07 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-BncWL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:56:07 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-1cgYve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:56:07 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-asNT1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:56:07 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-pmhJ66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:56:07 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-0gD1_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:56:07 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-4zYiD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:56:07 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-xp0LqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:56:07 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-jQ0kQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:56:08 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-Ij8ShH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:56:08 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-G11R4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:56:08 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-FfLiJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:56:08 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-OYyxOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:56:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:56:08 INFO - Running setup.py install for manifestparser 05:56:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:56:08 INFO - Running setup.py install for mozcrash 05:56:08 INFO - Running setup.py install for mozdebug 05:56:08 INFO - Running setup.py install for mozdevice 05:56:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:56:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:56:09 INFO - Running setup.py install for mozfile 05:56:09 INFO - Running setup.py install for mozhttpd 05:56:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:56:09 INFO - Running setup.py install for mozinfo 05:56:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:56:09 INFO - Running setup.py install for mozInstall 05:56:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:56:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:56:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:56:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:56:09 INFO - Running setup.py install for mozleak 05:56:09 INFO - Running setup.py install for mozlog 05:56:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:56:09 INFO - Running setup.py install for moznetwork 05:56:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:56:10 INFO - Running setup.py install for mozprocess 05:56:10 INFO - Running setup.py install for mozprofile 05:56:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:56:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:56:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:56:10 INFO - Running setup.py install for mozrunner 05:56:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:56:10 INFO - Running setup.py install for mozscreenshot 05:56:10 INFO - Running setup.py install for moztest 05:56:10 INFO - Running setup.py install for mozversion 05:56:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:56:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:56:11 INFO - Cleaning up... 05:56:11 INFO - Return code: 0 05:56:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:56: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')]} 05:56:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56: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')]} 05:56:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:11 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 0x1039fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb235d5e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038a2d30>, '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 0x1036c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103899b20>, '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.ck6m1LIBZM/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.70RcoQMgB3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:56:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:56:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:56:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ck6m1LIBZM/Render', 05:56:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:11 INFO - 'HOME': '/Users/cltbld', 05:56:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:11 INFO - 'LOGNAME': 'cltbld', 05:56:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:11 INFO - 'MOZ_NO_REMOTE': '1', 05:56:11 INFO - 'NO_EM_RESTART': '1', 05:56:11 INFO - 'PAGER': '/bin/cat', 05:56:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:11 INFO - 'PWD': '/builds/slave/test', 05:56:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:11 INFO - 'SHELL': '/bin/bash', 05:56:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70RcoQMgB3/Listeners', 05:56:11 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:56:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:11 INFO - 'USER': 'cltbld', 05:56:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:11 INFO - 'XPC_FLAGS': '0x0', 05:56:11 INFO - 'XPC_SERVICE_NAME': '0', 05:56:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:56:11 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-XMooji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:56:11 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-g6St_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:56:11 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-6dB_Fp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:56:11 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-xdrQ90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:56:11 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-R74CfU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:56:11 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-izY1yq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:56:11 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-FGTd_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:56:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-hvaguQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:56:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-6znmO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:56:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-dn7jHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:56:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-IMkO4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:56:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-lTiQsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:56:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-3sJE9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:56:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-8uGIWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:56:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-YEh9pw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:56:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-73w237-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:56:13 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-bZeX_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:56:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:15 INFO - Downloading blessings-1.6.tar.gz 05:56:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:56:15 INFO - Installing collected packages: blessings 05:56:15 INFO - Running setup.py install for blessings 05:56:15 INFO - Successfully installed blessings 05:56:15 INFO - Cleaning up... 05:56:15 INFO - Return code: 0 05:56:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:56:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:56:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:56:16 INFO - Reading from file tmpfile_stdout 05:56:16 INFO - Current package versions: 05:56:16 INFO - blessings == 1.6 05:56:16 INFO - blobuploader == 1.2.4 05:56:16 INFO - docopt == 0.6.1 05:56:16 INFO - manifestparser == 1.1 05:56:16 INFO - mozInstall == 1.12 05:56:16 INFO - mozcrash == 0.17 05:56:16 INFO - mozdebug == 0.1 05:56:16 INFO - mozdevice == 0.48 05:56:16 INFO - mozfile == 1.2 05:56:16 INFO - mozhttpd == 0.7 05:56:16 INFO - mozinfo == 0.9 05:56:16 INFO - mozleak == 0.1 05:56:16 INFO - mozlog == 3.2 05:56:16 INFO - moznetwork == 0.27 05:56:16 INFO - mozprocess == 0.22 05:56:16 INFO - mozprofile == 0.28 05:56:16 INFO - mozrunner == 6.11 05:56:16 INFO - mozscreenshot == 0.1 05:56:16 INFO - mozsystemmonitor == 0.3 05:56:16 INFO - moztest == 0.7 05:56:16 INFO - mozversion == 1.4 05:56:16 INFO - psutil == 3.1.1 05:56:16 INFO - requests == 1.2.3 05:56:16 INFO - wsgiref == 0.1.2 05:56:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:16 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 0x1039fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb235d5e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038a2d30>, '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 0x1036c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103899b20>, '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.ck6m1LIBZM/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.70RcoQMgB3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:56:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:56:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:56:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ck6m1LIBZM/Render', 05:56:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:16 INFO - 'HOME': '/Users/cltbld', 05:56:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:16 INFO - 'LOGNAME': 'cltbld', 05:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:16 INFO - 'MOZ_NO_REMOTE': '1', 05:56:16 INFO - 'NO_EM_RESTART': '1', 05:56:16 INFO - 'PAGER': '/bin/cat', 05:56:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:16 INFO - 'PWD': '/builds/slave/test', 05:56:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:16 INFO - 'SHELL': '/bin/bash', 05:56:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70RcoQMgB3/Listeners', 05:56:16 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:56:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:16 INFO - 'USER': 'cltbld', 05:56:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:16 INFO - 'XPC_FLAGS': '0x0', 05:56:16 INFO - 'XPC_SERVICE_NAME': '0', 05:56:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:56:16 INFO - Cleaning up... 05:56:16 INFO - Return code: 0 05:56:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:16 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 0x1039fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb235d5e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038a2d30>, '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 0x1036c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103899b20>, '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.ck6m1LIBZM/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.70RcoQMgB3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:56:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:56:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:56:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ck6m1LIBZM/Render', 05:56:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:16 INFO - 'HOME': '/Users/cltbld', 05:56:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:16 INFO - 'LOGNAME': 'cltbld', 05:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:16 INFO - 'MOZ_NO_REMOTE': '1', 05:56:16 INFO - 'NO_EM_RESTART': '1', 05:56:16 INFO - 'PAGER': '/bin/cat', 05:56:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:16 INFO - 'PWD': '/builds/slave/test', 05:56:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:16 INFO - 'SHELL': '/bin/bash', 05:56:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70RcoQMgB3/Listeners', 05:56:16 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:56:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:16 INFO - 'USER': 'cltbld', 05:56:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:16 INFO - 'XPC_FLAGS': '0x0', 05:56:16 INFO - 'XPC_SERVICE_NAME': '0', 05:56:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:16 INFO - Downloading/unpacking jsonschema 05:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:56:19 INFO - Downloading/unpacking functools32 (from jsonschema) 05:56: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 05:56: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 05:56: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 05:56: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 05:56: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 05:56: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 05:56:19 INFO - Downloading functools32-3.2.3-2.tar.gz 05:56:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:56:19 INFO - warning: no files found matching '*.txt' 05:56:19 INFO - no previously-included directories found matching 'build' 05:56:19 INFO - no previously-included directories found matching 'dist' 05:56:19 INFO - no previously-included directories found matching '.git*' 05:56:19 INFO - Installing collected packages: jsonschema, functools32 05:56:19 INFO - Running setup.py install for functools32 05:56:19 INFO - warning: no files found matching '*.txt' 05:56:19 INFO - no previously-included directories found matching 'build' 05:56:19 INFO - no previously-included directories found matching 'dist' 05:56:19 INFO - no previously-included directories found matching '.git*' 05:56:19 INFO - Successfully installed jsonschema functools32 05:56:19 INFO - Cleaning up... 05:56:19 INFO - Return code: 0 05:56:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:56:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:56:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:56:19 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 0x1039fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb235d5e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1038a2d30>, '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 0x1036c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103899b20>, '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.ck6m1LIBZM/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.70RcoQMgB3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:56:19 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 05:56:19 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 05:56:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ck6m1LIBZM/Render', 05:56:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:19 INFO - 'HOME': '/Users/cltbld', 05:56:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:19 INFO - 'LOGNAME': 'cltbld', 05:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:19 INFO - 'MOZ_NO_REMOTE': '1', 05:56:19 INFO - 'NO_EM_RESTART': '1', 05:56:19 INFO - 'PAGER': '/bin/cat', 05:56:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:19 INFO - 'PWD': '/builds/slave/test', 05:56:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:19 INFO - 'SHELL': '/bin/bash', 05:56:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70RcoQMgB3/Listeners', 05:56:19 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:56:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:19 INFO - 'USER': 'cltbld', 05:56:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:19 INFO - 'XPC_FLAGS': '0x0', 05:56:19 INFO - 'XPC_SERVICE_NAME': '0', 05:56:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:56:20 INFO - Downloading/unpacking flake8 05:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:56:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:56:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:56:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:56:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:56:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:56:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:56:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:56:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:56:23 INFO - no previously-included directories found matching 'docs/_build' 05:56:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:56:24 INFO - Running setup.py install for pep8 05:56:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:56:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:56:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:56:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:56:24 INFO - no previously-included directories found matching 'docs/_build' 05:56:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:56:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:56:24 INFO - Cleaning up... 05:56:24 INFO - Return code: 0 05:56:24 INFO - Running post-action listener: _resource_record_post_action 05:56:24 INFO - Running post-action listener: _start_resource_monitoring 05:56:24 INFO - Starting resource monitoring. 05:56:24 INFO - ##### 05:56:24 INFO - ##### Finished create-virtualenv step (success) 05:56:24 INFO - ##### 05:56:24 INFO - ##### 05:56:24 INFO - ##### Running install step. 05:56:24 INFO - ##### 05:56:24 INFO - Running pre-action listener: _resource_record_pre_action 05:56:24 INFO - Running main action method: install 05:56:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:56:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:56:24 INFO - Reading from file tmpfile_stdout 05:56:24 INFO - Detecting whether we're running mozinstall >=1.0... 05:56:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:56:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:56:24 INFO - Reading from file tmpfile_stdout 05:56:24 INFO - Output received: 05:56:24 INFO - Usage: mozinstall [options] installer 05:56:24 INFO - Options: 05:56:24 INFO - -h, --help show this help message and exit 05:56:24 INFO - -d DEST, --destination=DEST 05:56:24 INFO - Directory to install application into. [default: 05:56:24 INFO - "/builds/slave/test"] 05:56:24 INFO - --app=APP Application being installed. [default: firefox] 05:56:24 INFO - mkdir: /builds/slave/test/build/application 05:56:24 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'] 05:56:24 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 05:56:58 INFO - Reading from file tmpfile_stdout 05:56:58 INFO - Output received: 05:56:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:56:58 INFO - Running post-action listener: _resource_record_post_action 05:56:58 INFO - ##### 05:56:58 INFO - ##### Finished install step (success) 05:56:58 INFO - ##### 05:56:58 INFO - ##### 05:56:58 INFO - ##### Running run-tests step. 05:56:58 INFO - ##### 05:56:58 INFO - Running pre-action listener: _resource_record_pre_action 05:56:58 INFO - Running main action method: run_tests 05:56:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:56:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:56:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:56:58 INFO - 2016-05-30 05:56:58.609 screenresolution[1638:7397] starting screenresolution argv=screenresolution get 05:56:58 INFO - 2016-05-30 05:56:58.612 screenresolution[1638:7397] Display 0: 1600x1200x32@60 05:56:58 INFO - 2016-05-30 05:56:58.618 screenresolution[1639:7399] starting screenresolution argv=screenresolution list 05:56:58 INFO - Available Modes on Display 0 05:56:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:56:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:56:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:56:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:56:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:56:58 INFO - Intel Iris: 05:56:58 INFO - Chipset Model: Intel Iris 05:56:58 INFO - Type: GPU 05:56:58 INFO - Bus: Built-In 05:56:58 INFO - VRAM (Dynamic, Max): 1536 MB 05:56:58 INFO - Vendor: Intel (0x8086) 05:56:58 INFO - Device ID: 0x0a2e 05:56:58 INFO - Revision ID: 0x0009 05:56:58 INFO - Displays: 05:56:58 INFO - Display: 05:56:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:56:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:56:58 INFO - Display Serial Number: 1600x1200 60 05:56:58 INFO - Main Display: Yes 05:56:58 INFO - Mirror: Off 05:56:58 INFO - Online: Yes 05:56:58 INFO - Rotation: Supported 05:56:58 INFO - Return code: 0 05:56:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:56:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:56:58 INFO - Python 2.7.3 05:56:58 INFO - Return code: 0 05:56:58 INFO - grabbing minidump binary from tooltool 05:56:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:58 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 0x1038a2d30>, '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 0x1036c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103899b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:56:58 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:56:58 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:56:58 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:56:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:56:58 INFO - Return code: 0 05:56:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:56:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:56:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:56:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:56:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:56:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:56:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:57:00 INFO - Return code: 0 05:57:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:57:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0116 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:57:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ck6m1LIBZM/Render', 05:57:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:00 INFO - 'HOME': '/Users/cltbld', 05:57:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:00 INFO - 'LOGNAME': 'cltbld', 05:57:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:57:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:57:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:00 INFO - 'MOZ_NO_REMOTE': '1', 05:57:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:57:00 INFO - 'NO_EM_RESTART': '1', 05:57:00 INFO - 'PAGER': '/bin/cat', 05:57:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:00 INFO - 'PWD': '/builds/slave/test', 05:57:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:57:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:00 INFO - 'SHELL': '/bin/bash', 05:57:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70RcoQMgB3/Listeners', 05:57:00 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:57:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:00 INFO - 'USER': 'cltbld', 05:57:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:00 INFO - 'XPC_FLAGS': '0x0', 05:57:00 INFO - 'XPC_SERVICE_NAME': '0', 05:57:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:57:00 INFO - mozversion application_buildid: 20160530040947 05:57:00 INFO - mozversion application_changeset: 00dccad34edae14b16de663c884a36ef2ec575b4 05:57:00 INFO - mozversion application_display_name: Nightly 05:57:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:57:00 INFO - mozversion application_name: Firefox 05:57:00 INFO - mozversion application_remotingname: firefox 05:57:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:57:00 INFO - mozversion application_vendor: Mozilla 05:57:00 INFO - mozversion application_version: 49.0a1 05:57:00 INFO - mozversion platform_buildid: 20160530040947 05:57:00 INFO - mozversion platform_changeset: 00dccad34edae14b16de663c884a36ef2ec575b4 05:57:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:57:00 INFO - mozversion platform_version: 49.0a1 05:57:00 INFO - using testdate: 1464613020 05:57:00 INFO - actual date: 1464613020 05:57:00 INFO - starting webserver on 'localhost:49275' 05:57:00 INFO - SUITE-START | Running 2 tests 05:57:00 INFO - TEST-START | dromaeo_css 05:57:00 INFO - operating with platform_type : mac_ 05:57:00 INFO - Initialising browser for dromaeo_css test... 05:57:00 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpd0RLvC/profile http://localhost:49275/getInfo.html) 05:57:03 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 05:57:03 INFO - PROCESS | 1657 | colorDepth:24 05:57:03 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 05:57:03 INFO - PROCESS | 1657 | __metrics 05:57:03 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 05:57:04 INFO - TEST-INFO | 1657: exit 0 05:57:04 INFO - Browser initialized. 05:57:04 INFO - Running cycle 1/1 for dromaeo_css test... 05:57:04 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpd0RLvC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:57:16 INFO - PROCESS | 1660 | RSS: Main: 252424192 05:57:16 INFO - PROCESS | 1660 | 05:58:05 INFO - PROCESS | 1660 | 1464613085935 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 06:00:16 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 06:00:17 INFO - PROCESS | 1660 | RSS: Main: 264749056 06:00:17 INFO - PROCESS | 1660 | 06:03:20 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 06:03:20 INFO - PROCESS | 1660 | RSS: Main: 275943424 06:03:20 INFO - PROCESS | 1660 | 06:06:22 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 06:06:23 INFO - PROCESS | 1660 | RSS: Main: 278216704 06:06:23 INFO - PROCESS | 1660 | 06:09:25 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 06:09:25 INFO - PROCESS | 1660 | RSS: Main: 279654400 06:09:25 INFO - PROCESS | 1660 | 06:12:28 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 06:12:29 INFO - PROCESS | 1660 | RSS: Main: 283086848 06:12:29 INFO - PROCESS | 1660 | 06:15:31 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 06:15:32 INFO - PROCESS | 1660 | RSS: Main: 283783168 06:15:32 INFO - PROCESS | 1660 | 06:15:32 INFO - PROCESS | 1660 | __start_tp_report 06:15:32 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 06:15:32 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 06:15:32 INFO - PROCESS | 1660 | |i|pagename|runs| 06:15:32 INFO - PROCESS | 1660 | |0;dojo.html;3244.334911343175;3500.4749287606874;3512.718982481402;3557.9644203557955;3571.71426285897;5339.06566350888;5622.465865742748;5848.152017957394;5907.5864689471755;5949.286085669721;5750.482456578907;5879.88240235195;5968.522518198533;6016.7593296268205;6019.217501724781;5238.926020165866;5298.470152984709;5405.162199859008;5450.29146210992;5475.561955043587;122.53010417433472;5312.893742125155;5320.095583750753;5388.568914486851;5418.512333889949;5322.866928326804;5352.652077614962;5363.195520671891;5368.651037682538;5405.243265942771;5311.521963023347;5427.674339539641;5453.590980676426;5459.563234941234;5476.315460567429;5296.841094767163;5317.12267475866;5350.678959262456;5364.329458817648;5395.865103372408;5797.275340582427;5942.019566771521;5948;5972.044472884318;5990.64056156632;4781.378420805273;5950.928832810093;6270.216222972129;6275.7489700411925;6278.497720182374;5741.368449470555;6355;6356.364363563606;6370;6434.388733070351;6076.5442591805795;6118.816435506942;6125.326214116443;6129.540284478593;6143.907841382383;6267.059941008794;6311.053341998645;6316.589421687576;6450.548461607643;6458.321876203025;6201.441870231701;6244.781432649836;6266.342034086447;6283.120363149163;6306.211723534559;6218.253809542884;6282.811515654537;6338.746450141943;6350.015747559136;6375.744970201233;6152;6192.783252586138;6193.4425901669065;6290.308066112724;6294.748210071637;6208;6209.720562574695;6229.345918678474;6230.937690623035;6261.686915654199;5063.974680126649;5082.2122571001555;5120.974395128075;5121.411037730634;5128.692278463304;4514.435695538058;4519.231730605805;4526.841560545365;4539.568740969603;4647.488776234612;5241.42344342122;5789;6014.699265036818;6020.367861374492;6038.184845045863;5096.719680417613;6157.2611286645;6292.716827742763;6324.620522768649;6342.460890824239;5840.29916410025;6001.759929602855;6041.093835924558;6080.179175811298;6096.542759293071;5553.972230138905;5582.944170558324;5587.860303492445;5592.384837667853;5600.103983362643;6326.905096423649;6555.868882622321;6589.80230593083;6605.438537724252;6617.272100068989;4654.953450465452;6244.718987645476;6272.8118156455375;6335.429811317003;6344.302126766144;6503.2846386898345;6539.738410463528;6541.8691626166255;6551.246606640298;6597.835054123685;6104.186323682738;6120.904292401297;6291.307956124914;6298.905516417349;6309.558330916791;6184.721687524162;6353.841153971188;6356.300806911144;6365.777197798055;6367.458766004756;5219.556337711337;5437.32033495813;5599.99200143978;6158.815235542941;6312.779052733133;6091.177691011657;6210.720517576629;6220.222472190976;6319.368063193644;6319.778807741707;5589.329280486368;5661.915071274017;5666.291713535808;5721.771129154954;5993.460588547052;5851.590388672752;5903.557233207441;5924.31870334917;5924.31870334917;5944;5790.1893734877995;5793.884122317446;6035.909461358171;6080.087986801838;6097.664628445301 06:15:32 INFO - PROCESS | 1660 | |1;ext.html;477.87097483679406;502.21403503516996;514.3031192733846;546.9962618594878;557.5316733943488;37265;40856;40865.59134408655;40877;40914;33545.496817547755;40429;40668.38997415034;41054.794726026405;41083.58916410835;9478.81042379152;9711.951440242825;9729.805403891953;9731.026897310283;9750.512474376288;8378.916210837875;9191.264698824078;9431.830678943847;9451.669191578296;9514.429134251932;8188.385871059664;9482.288828337867;9493;9523.523823808817;9549;8198.303144232717;9441.386309889904;9451.858222126673;9526.856777186713;9545.236381089566;9475.052494750538;9554.331196816225;9581.233501319877;9607;9607.375520591138;32673;39047;39221;39434.60565394338;39453.40819887675;3107.9533806992913;3350.27968986669;3370.8314584270934;3407.16524451511;3551.2187318790084;29675.406491870257;29787.55318670222;30375.696243037506;30653.69346306553;30828.38343233123;3138.905832825019;3144.24538110854;3144.3868445653106;3165.6359518655418;3269.820159891205;357861;408914;414754;414784.9260753707;415016;32113.51829722556;32298.83850580779;32442.864499742394;32456.02631921046;32495.51256731151;34178;34473.482897756556;34517.65482345194;35597;35692.10769730777;16497.422590209284;16572.917135414245;16676.916615416845;16677.749833752503;16683.332666693197;12368.505259789688;12413.813792793115;12755.107142500121;12860.935695321463;12878.67803304925;16271.51185464438;16393.754093688603;19618.50953726168;19650.21399144047;19766.90116549408;8306.54314012723;8477.364197685198;8479.618417171243;8480.915190848013;8520.531370774543;890.9910900890908;973.956171972263;992.1467537917385;999.3903718731568;1039.719275795531;30351.392972140435;30458.69541304603;30506.54240186399;30558;30720.69279307223;1048.8846226915034;1069.4759567811716;1087.3693257910393;1147.7532330548804;1198.4846515998204;14895.404183832525;15801.604959875865;15998.520044398687;16047.919760401357;16268.51194464168;639470;645133;653225;653316;654099;57149;59710;59780;59812.40187598155;59896.70051649714;1410.3512384303335;1420.7584710599224;1432.44134787433;1434.9210793406462;1440.7262620102147;30026;30070.744694148463;30381.696183037886;31105.68894311028;31549.8422507886;57791;58511.82976340364;58517;58627.70686146542;58725;592366;614747;614762.9261853662;623202;625835;44009;44262.55737442584;44488.88777780452;44883.55116448794;45081.323780142666;46915;47898.76050619724;48111.27833082437;48192.036159275915;49339;35704.64295357013;37801.243975120895;39638.60361396349;40922;41631.58368416398;35945.1013724659;38606.80696596499;38642;40630.79684601558;40752.388714168716;32601.673983259865;38597.80701096477;38628.034299141626;39287;39433.60566394414;33789.66210337865;36843;40733;42660.78669606632;44500.55499444964;31386.372272553963;31832.045038648877;32385;33005.33989320152;33452.832735836164 06:15:32 INFO - PROCESS | 1660 | |2;jquery.html;363.7181184581947;374.2048147686167;377.2963423215702;382.17640983680167;397.4931961748772;19252.807471925276;20648;20863.791362086373;21067.47331316718;21291.467713307135;20749.688754668692;21362.786372136274;21440;21717.131314747392;21824.23615173469;5386.761044959661;5922.555808314372;6140.171076904628;6174.259088909337;6186.783462578811;5884.352721200665;6109.755609775603;6133.6626485543275;6175.505959523227;6176.752929882799;5992.610480318787;6015.308239552463;6016.849578760544;6019.518438524908;6021.036634138539;5132.461091585405;5722.885542289173;5995.220621319222;5996.610220335686;6023.6988150592515;5183;5232.92150617741;5237.528622424;5286.365636123652;5290.65610735301;14395.568132956028;20841.478963026046;22145.33563993083;22619.77380226193;23607;1974.1412485568824;2290.404494831339;2306.423394151462;2350.224425939436;2457.6559281700575;9821.852672209923;9987.600495980152;10295.742606434898;10504.894951050544;16087.597810054725;1870.681984062699;1923.4422017614875;1944.8346890514324;1952.0630097553253;1957.765068191812;78430.60784696096;101789;109078;116155;121598.39200804027;9538;11521.539138434537;11797.764044719057;11802.704932376759;11970.982466490272;14789.704205915821;14839.554813355617;14842;14855.628609284855;14882.925585372004;8546.829063418696;8748.781280467912;8749.993750718622;8833.14503954164;8835;8150.225728555778;8529.189726975927;8640.006399264039;8746.34402419821;8755.0807165248;4469.754163521037;4626.352310676508;4629.814807407666;4667.393238878924;4688.015516741454;1686.8819182657096;1943.416974907522;1971.6648169811167;1974.2952155546993;1975.7530308711412;849.1211595998112;947.2901412949554;983.5721461164417;1066.861308029951;1067.4182570498942;20400.97995100239;20957.580848382946;21096.894515527325;21144.682829757567;21206.363809085753;950.1543626172712;950.2303134461108;962.4225464721111;970.4419958523736;982.5627595719978;10664.840027399596;11107.722306942229;11301.152413569016;11326.773464530828;11350.48922798476;604090;604542;613734;615504;619108;122037.38981305037;122413;123254.38372808078;123467;127660;966.8501382285757;982.5578489679673;1005.6882366466418;1006.104566935428;1009.8384258518602;18773.6245275091;18863;18929.526761831065;19127.52181195481;19377.224911003403;50108.24837627366;50939.74530127326;52021.47978520166;52307;52359.47640523546;55153;69694.65152674205;71731.56536869338;75225.34908485594;79356.20643793719;6830.21452532965;7283.688935991572;7658.119316278699;8157.388195885451;8285.668573257244;10331.84502232452;12348;12645.620631381073;12879.549215777402;12932.094753367173;10888.67333979982;10889.455527223765;10932.180086493387;11011.174161937726;11263.098952083978;9621.326507144433;9797.06927841854;10603.363798172135;10834.07910327631;11019.504122314354;10752;11341.432928353714;11382.772344553228;11416.771664566828;11704.005159561533;8089.191080891864;8122.75631731049;8128.674853005814;8254.83490330178;8318.085010648945;10826.004620311562;11692.94153529227;11862.940685296518;11921.94039029834;11954.342511161969 06:15:32 INFO - PROCESS | 1660 | |3;mootools.html;1311.3508813137496;1576.4797616786461;1749.9737503937436;1897.77226732792;1909.3508207209545;8046.436749427542;8615.784605384868;8630.266427353683;9090.63637454501;9117.680881169152;8724.8691269631;8808.515531645757;8874.068222836606;8955.552222388871;8966;2361.161787565415;2381.7261014983324;2385.594448943675;2386.093284551868;2386.868722220277;2383.6305372667266;2389.7251816041116;2404.290734233405;2405.4707964247837;2425.5876500994873;2403.9158629447975;2405.0379848060743;2405.36257891658;2411.7829395354415;2419.080749315258;2374.5725769361507;2389.534040862024;2393.640953856918;2406.8796560171922;2411.0717373811035;2349.835511514195;2357.233898982837;2357.481354102095;2358.8466749661216;2359.858408495496;8782.824343513157;8878.600462979182;8914.955425222897;8926.196642302224;8990.28077753778;4756.500567440404;5251.264822924793;5333.333333333333;5438.184272359138;5451.373092094381;3250.333681595267;3319.767616266862;3565.073080998933;3629.0927268182954;3731.962680373216;962.7833737409027;1000.2298230362653;1011.9139873110795;1107.800595892739;1111.372074777748;10703.518341674644;10720.303180293256;11581.942090289494;11606.187566870323;11610.70973225676;4728.550787675165;4737.265723812815;4802.6638135330195;4861.368022157097;4863.9513604864205;9395.342326037111;9446.102620251066;9840.163586095263;9915.058069483388;9925.057119573628;5318.388385335736;5355.464453554613;5417.404085550586;5418.106012507892;5425.647332923348;4439.755813430293;4829.806807727651;4832.251001094836;4918.2376731606655;4944.554990050912;4053.3717273822604;4060.4979083169787;4070.247004304213;4073.918521629551;4089.2026054919006;1295.4688577683105;1311.2591385866954;1317.985151433391;1324.1723922548408;1330.2750001249342;854.1159899503983;889.1597440418742;932.8572498689051;937.5124935033745;940.4827344960245;5913.58604897653;5991.820245392645;6005.669688167192;6089.5737298388685;6211.223597050369;778.4978688745728;782.460102529253;833.2334252487726;900.7612982559627;996.3125443444001;3338.582677165354;3934.7639141651593;3966.2265858157957;4026.53694825093;4042;10476.319039262577;10840.349579025125;10890.509927053301;10954.178436617285;10982.780344393068;10954.890451095547;10968.451577421098;10974.615888443866;10978.451077446256;10980.780384392267;7924.564148971746;8244.711435099864;8326.750197494084;8329.750107496784;8360.289375403037;5727.455891690282;5879.73541190656;5906.084556893688;5934.228550288435;5982.49148822355;10088.596456141671;10103.545340459845;10190.796184076426;10193.898061019296;10252.897471025195;9963.25275604318;10191.84712229332;10197.9490102549;10400.635977740743;10422.426766527611;6622.304658010839;6849.212340580897;7029.402500787491;7129;7294.379977701955;8508.574571271536;8521.44610600309;8526.488410695378;8608.268297194558;8615.181557752003;7482.925170748223;7669.271419215165;7682.769516914502;7707;7759.650815713419;7335.816604584928;7412.777616671508;7463.962680186564;7492.325690687864;7644.15914249421;7035.261297563682;7108.324709152622;7127.679254433667;7157;7157.962095496228;4466.709663871838;4899.559039686303;4943.802247910187;5058.418281897481;5113.335266415343;5043.142665746906;5078.085944529872;5086.949130508648;5126.512981266774;5194.714290713896 06:15:32 INFO - PROCESS | 1660 | |4;prototype.html;306.62438512821797;310.74518894506514;311.5342562868512;316.9793963392378;318.95694081299047;5551.972240138799;5601.495865372116;5647.5764317676185;5652.689102099388;5656.490915817576;4701.576858082772;5475.808346707866;5476;5480.698561579112;5498.697571633558;3347.698707116359;3415.4023045966856;3486.825658717054;3516.593362654946;3852.6339997700316;3473.392156372637;3729.5710993235716;3985.940210896839;4207.579242075783;4247.638950689196;4222.493300803908;4230.688486569158;4235.703500754948;4251.638610718077;4253.787310634456;3982.283189025974;4159.9376009359885;4203.495580530325;4229.534751177368;4236.745795252279;3106.829124398157;3403.710684591813;3618.028174644825;3641.1625326175126;3641.18073433478;4600.07998400318;5282.947170528284;5293.179557168645;5296.099663057251;5340.225667278261;4267.551907049778;4805.855824325241;4822.758862056919;4832.9609134036;4842.346283251751;5230.006298803253;5286.286351342558;5656.444477768647;5889.263842019747;5935.643861368331;3501.3347463981763;3955.564887862333;3963.573113679102;3971.007248187953;3985.3822657488136;4920.507949205086;5967.060705270761;6104.825490744833;6123.9387606124255;6134.785282515135;3704.240630670706;3991.7604943703473;4005.136327525833;4020.6783457323345;4033.51597808259;4054.959450405458;4077.4495443115393;4100.876973690794;4103.692223083281;4123.60825721556;4667;4673.299005149255;4725.385699858996;4782.876024134325;4791.185498465269;5659.179418984307;5883.7058147092475;5891.351951285355;5904.675242861684;5958.553108516879;1126.8929451702074;1338.2305174524688;1382.523029554802;1384.1279993604167;1392.6839137015422;745.1505283976271;801.2468279816953;834.5284914023557;855.7347222361088;857.1428571428571;4910.772306923269;5101.642884998088;5352.357717073976;5489.807856724996;5501.477359650827;5552.140647566506;5638.520725738276;5755.539556835443;5787.75885504119;6097.298810636834;4639.489656137822;4691.413573303337;4931.608170037529;5510.9448905510435;5546.140348246028;2528.633348164506;3072.339447018902;3183.4428974929297;3241.0067808297727;3253.7711667507833;4896.971635956418;5760.3951585083805;6288.182536270256;6347.301796802348;6378.24598235489;5820.650760954356;5902.498287645598;5979.634866547358;6333.936660633335;6370.904436433457;6322.272938612026;6330.208723909512;6338.556301058881;6345.619262844244;6406.871862562816;5446.891062178814;6091.695415229309;6093.29927058394;6161.168242287234;6181.289151747426;5219.582433405242;5250.579953603779;5398.352197736297;6014.789482368096;6064.332923378513;5857.853553661193;6251.843703907439;6288.874222515616;6330.272018717907;6353.77761778354;3030.727234548813;3122.703343182394;3312.2381852173653;3465.86136554535;3507.3511400391008;4327.545607711271;4380.451834014459;4506.301523263899;4608.949017687097;4868.63678170113;4666.929996049994;4734.834280800155;4804.5195480451675;5058.266551350107;5105.846824595268;3854.8843534694006;4507.346434767006;4608.986023074918;4813.229883218668;4823.927641085456;4491.438570178728;4506.90986180268;4656.627469802475;4904.553156818675;4931.457539670705;5500.669959802424;5709.629688874557;5834.795782147605;5847.619904706181;5870.9412905870395;5226.686398816083;5354.009508240988;5366.543843773166;5770.0190967536455;5823.912641310299 06:15:32 INFO - PROCESS | 1660 | |5;yui.html;617.0127795527155;716.1907045039105;721.0656298662014;821.511200835503;853.9274161696255;14570.12579245246;15194.468193613198;15409.922950385246;15455.45905893294;15469.535913922586;13714.519991800289;14140.434382624682;14150.292485375741;14155;14162.858371416283;702.0627462337785;879.3536750488386;879.7668617816252;888.1473785166248;896.2964073202537;902.6660135749781;906.1934877958622;906.4289497616526;907.2469850024497;907.4555266839876;802.546561192927;941.8963913969458;951.5004622573169;951.6621599332241;954.7613096725819;798.6845196147489;918.9908100919039;939.2908354192575;939.6147579492375;946.4037656276536;685.3626127701236;695.8538706871564;700.7617410080597;702.5889854435162;703.8416356319788;12061;14458.783118253235;14608.269586520631;14768;14774.039687420283;13082.638151825691;14263.144211347353;14267.286635668175;14268.357923893345;14330.283485825667;11075.280106793032;11853.88146118545;11967.461464234131;12201.389930503528;12277.20198187115;3814.5041144651295;3882.9611703883165;3894.8665379684594;3903.7657740535374;4024.2555127301544;79308;80212.59893700553;80279.5986020072;80597.19402805956;84181;9545.570449329796;9560.378575392577;9621.855672164924;9694.95152524247;9966.15287700539;19735.506612334808;19741.40775776729;19766.308179213658;19782;19806.801931980495;7568.772936811904;7691.884676721844;7780.221977802287;7980.960095199603;8146.796330091676;3441.63862794408;4485.102979404132;4528.41130653013;4551.95448045522;4660.557247061523;3526.3123690880034;3628.1473853644375;3633.3096711625235;3644.580873199616;3651.6165802590876;699.9860002799916;737.5095561451682;745.7725393754957;755.845051764398;757.7802437293234;586.3813676571173;691.408845437154;698.8329489752025;707.6002058836843;709.4359983812881;8141.552214628252;9111.225545828545;9422.387544809566;9527.952360238154;9555.474448905377;617.2286433911704;624.5877720704405;644.9970296189471;662.1722846441947;717.9210286868545;4267.935980960288;4294.849680261176;4502.009557897258;4503.144402563503;4595.862124136282;54853.35439936808;63676;96725.5163724191;97845.5107724471;98889;65759.34240657532;65763;66500;77853.61073194598;79921;73535.63232183912;74274.62862685652;74426;74687;75787;8838.823223535364;9644.75888102803;9671.226301895973;9728;9761;43022.569774303105;43698.90752731207;46030.84922876955;46090.539094608626;46147.07705845931;46126;49179.508204917496;57625.423745762004;58064;58472.830543388045;12673.493060277855;12799.80800288015;12888;12897.871021289668;13450.798238026633;14316.57050288493;14362;14374.856251437352;14414.495492657707;14649.92675036618;13158.934205328913;13461.528846490326;13791.448342066205;13951.651208719863;13971.860281397056;13262.27057511846;13281.203127812361;13358.198508089547;13385.933070334586;13549.729005420033;12810.807837882252;12844.743105138032;12965.870341296844;13171.209727416002;13207.60377188686;10767.461626918779;11290.322580644859;11293.378864162221;11369.886301137214;11391.316521008766;10780.353178809297;11155.457892979897;11398.829017564578;11426.485808138488;11428.485718142538 06:15:32 INFO - PROCESS | 1660 | __end_tp_report 06:15:32 INFO - PROCESS | 1660 | __start_cc_report 06:15:32 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,417 06:15:32 INFO - PROCESS | 1660 | __end_cc_report 06:15:32 INFO - PROCESS | 1660 | __startTimestamp1464614132203__endTimestamp 06:15:32 INFO - PROCESS | 1660 | 06:15:32 INFO - PROCESS | 1660 | ------- Summary: start ------- 06:15:32 INFO - PROCESS | 1660 | Number of tests: 6 06:15:32 INFO - PROCESS | 1660 | 06:15:32 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5783.27 Median:6014.70 stddev:776.28 (12.9%) stddev-sans-first:752.68 06:15:32 INFO - PROCESS | 1660 | Values: 3244.3 3500.5 3512.7 3558.0 3571.7 5339.1 5622.5 5848.2 5907.6 5949.3 5750.5 5879.9 5968.5 6016.8 6019.2 5238.9 5298.5 5405.2 5450.3 5475.6 122.5 5312.9 5320.1 5388.6 5418.5 5322.9 5352.7 5363.2 5368.7 5405.2 5311.5 5427.7 5453.6 5459.6 5476.3 5296.8 5317.1 5350.7 5364.3 5395.9 5797.3 5942.0 5948.0 5972.0 5990.6 4781.4 5950.9 6270.2 6275.7 6278.5 5741.4 6355.0 6356.4 6370.0 6434.4 6076.5 6118.8 6125.3 6129.5 6143.9 6267.1 6311.1 6316.6 6450.5 6458.3 6201.4 6244.8 6266.3 6283.1 6306.2 6218.3 6282.8 6338.7 6350.0 6375.7 6152.0 6192.8 6193.4 6290.3 6294.7 6208.0 6209.7 6229.3 6230.9 6261.7 5064.0 5082.2 5121.0 5121.4 5128.7 4514.4 4519.2 4526.8 4539.6 4647.5 5241.4 5789.0 6014.7 6020.4 6038.2 5096.7 6157.3 6292.7 6324.6 6342.5 5840.3 6001.8 6041.1 6080.2 6096.5 5554.0 5582.9 5587.9 5592.4 5600.1 6326.9 6555.9 6589.8 6605.4 6617.3 4655.0 6244.7 6272.8 6335.4 6344.3 6503.3 6539.7 6541.9 6551.2 6597.8 6104.2 6120.9 6291.3 6298.9 6309.6 6184.7 6353.8 6356.3 6365.8 6367.5 5219.6 5437.3 5600.0 6158.8 6312.8 6091.2 6210.7 6220.2 6319.4 6319.8 5589.3 5661.9 5666.3 5721.8 5993.5 5851.6 5903.6 5924.3 5924.3 5944.0 5790.2 5793.9 6035.9 6080.1 6097.7 06:15:32 INFO - PROCESS | 1660 | 06:15:32 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:68589.43 Median:39546.01 stddev:151454.59 (383.0%) stddev-sans-first:151793.12 06:15:32 INFO - PROCESS | 1660 | Values: 477.9 502.2 514.3 547.0 557.5 37265.0 40856.0 40865.6 40877.0 40914.0 33545.5 40429.0 40668.4 41054.8 41083.6 9478.8 9712.0 9729.8 9731.0 9750.5 8378.9 9191.3 9431.8 9451.7 9514.4 8188.4 9482.3 9493.0 9523.5 9549.0 8198.3 9441.4 9451.9 9526.9 9545.2 9475.1 9554.3 9581.2 9607.0 9607.4 32673.0 39047.0 39221.0 39434.6 39453.4 3108.0 3350.3 3370.8 3407.2 3551.2 29675.4 29787.6 30375.7 30653.7 30828.4 3138.9 3144.2 3144.4 3165.6 3269.8 357861.0 408914.0 414754.0 414784.9 415016.0 32113.5 32298.8 32442.9 32456.0 32495.5 34178.0 34473.5 34517.7 35597.0 35692.1 16497.4 16572.9 16676.9 16677.7 16683.3 12368.5 12413.8 12755.1 12860.9 12878.7 16271.5 16393.8 19618.5 19650.2 19766.9 8306.5 8477.4 8479.6 8480.9 8520.5 891.0 974.0 992.1 999.4 1039.7 30351.4 30458.7 30506.5 30558.0 30720.7 1048.9 1069.5 1087.4 1147.8 1198.5 14895.4 15801.6 15998.5 16047.9 16268.5 639470.0 645133.0 653225.0 653316.0 654099.0 57149.0 59710.0 59780.0 59812.4 59896.7 1410.4 1420.8 1432.4 1434.9 1440.7 30026.0 30070.7 30381.7 31105.7 31549.8 57791.0 58511.8 58517.0 58627.7 58725.0 592366.0 614747.0 614762.9 623202.0 625835.0 44009.0 44262.6 44488.9 44883.6 45081.3 46915.0 47898.8 48111.3 48192.0 49339.0 35704.6 37801.2 39638.6 40922.0 41631.6 35945.1 38606.8 38642.0 40630.8 40752.4 32601.7 38597.8 38628.0 39287.0 39433.6 33789.7 36843.0 40733.0 42660.8 44500.6 31386.4 31832.0 32385.0 33005.3 33452.8 06:15:32 INFO - PROCESS | 1660 | 06:15:32 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:34627.67 Median:2328.32 stddev:101504.36 (4359.5%) stddev-sans-first:101756.51 06:15:32 INFO - PROCESS | 1660 | Values: 363.7 374.2 377.3 382.2 397.5 19252.8 20648.0 20863.8 21067.5 21291.5 20749.7 21362.8 21440.0 21717.1 21824.2 5386.8 5922.6 6140.2 6174.3 6186.8 5884.4 6109.8 6133.7 6175.5 6176.8 5992.6 6015.3 6016.8 6019.5 6021.0 5132.5 5722.9 5995.2 5996.6 6023.7 5183.0 5232.9 5237.5 5286.4 5290.7 14395.6 20841.5 22145.3 22619.8 23607.0 1974.1 2290.4 2306.4 2350.2 2457.7 9821.9 9987.6 10295.7 10504.9 16087.6 1870.7 1923.4 1944.8 1952.1 1957.8 78430.6 101789.0 109078.0 116155.0 121598.4 9538.0 11521.5 11797.8 11802.7 11971.0 14789.7 14839.6 14842.0 14855.6 14882.9 8546.8 8748.8 8750.0 8833.1 8835.0 8150.2 8529.2 8640.0 8746.3 8755.1 4469.8 4626.4 4629.8 4667.4 4688.0 1686.9 1943.4 1971.7 1974.3 1975.8 849.1 947.3 983.6 1066.9 1067.4 20401.0 20957.6 21096.9 21144.7 21206.4 950.2 950.2 962.4 970.4 982.6 10664.8 11107.7 11301.2 11326.8 11350.5 604090.0 604542.0 613734.0 615504.0 619108.0 122037.4 122413.0 123254.4 123467.0 127660.0 966.9 982.6 1005.7 1006.1 1009.8 18773.6 18863.0 18929.5 19127.5 19377.2 50108.2 50939.7 52021.5 52307.0 52359.5 55153.0 69694.7 71731.6 75225.3 79356.2 6830.2 7283.7 7658.1 8157.4 8285.7 10331.8 12348.0 12645.6 12879.5 12932.1 10888.7 10889.5 10932.2 11011.2 11263.1 9621.3 9797.1 10603.4 10834.1 11019.5 10752.0 11341.4 11382.8 11416.8 11704.0 8089.2 8122.8 8128.7 8254.8 8318.1 10826.0 11692.9 11862.9 11921.9 11954.3 06:15:32 INFO - PROCESS | 1660 | 06:15:32 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5749.49 Median:4944.18 stddev:3210.12 (64.9%) stddev-sans-first:3201.79 06:15:32 INFO - PROCESS | 1660 | Values: 1311.4 1576.5 1750.0 1897.8 1909.4 8046.4 8615.8 8630.3 9090.6 9117.7 8724.9 8808.5 8874.1 8955.6 8966.0 2361.2 2381.7 2385.6 2386.1 2386.9 2383.6 2389.7 2404.3 2405.5 2425.6 2403.9 2405.0 2405.4 2411.8 2419.1 2374.6 2389.5 2393.6 2406.9 2411.1 2349.8 2357.2 2357.5 2358.8 2359.9 8782.8 8878.6 8915.0 8926.2 8990.3 4756.5 5251.3 5333.3 5438.2 5451.4 3250.3 3319.8 3565.1 3629.1 3732.0 962.8 1000.2 1011.9 1107.8 1111.4 10703.5 10720.3 11581.9 11606.2 11610.7 4728.6 4737.3 4802.7 4861.4 4864.0 9395.3 9446.1 9840.2 9915.1 9925.1 5318.4 5355.5 5417.4 5418.1 5425.6 4439.8 4829.8 4832.3 4918.2 4944.6 4053.4 4060.5 4070.2 4073.9 4089.2 1295.5 1311.3 1318.0 1324.2 1330.3 854.1 889.2 932.9 937.5 940.5 5913.6 5991.8 6005.7 6089.6 6211.2 778.5 782.5 833.2 900.8 996.3 3338.6 3934.8 3966.2 4026.5 4042.0 10476.3 10840.3 10890.5 10954.2 10982.8 10954.9 10968.5 10974.6 10978.5 10980.8 7924.6 8244.7 8326.8 8329.8 8360.3 5727.5 5879.7 5906.1 5934.2 5982.5 10088.6 10103.5 10190.8 10193.9 10252.9 9963.3 10191.8 10197.9 10400.6 10422.4 6622.3 6849.2 7029.4 7129.0 7294.4 8508.6 8521.4 8526.5 8608.3 8615.2 7482.9 7669.3 7682.8 7707.0 7759.7 7335.8 7412.8 7464.0 7492.3 7644.2 7035.3 7108.3 7127.7 7157.0 7158.0 4466.7 4899.6 4943.8 5058.4 5113.3 5043.1 5078.1 5086.9 5126.5 5194.7 06:15:32 INFO - PROCESS | 1660 | 06:15:32 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4585.49 Median:4900.76 stddev:1465.20 (29.9%) stddev-sans-first:1433.68 06:15:32 INFO - PROCESS | 1660 | Values: 306.6 310.7 311.5 317.0 319.0 5552.0 5601.5 5647.6 5652.7 5656.5 4701.6 5475.8 5476.0 5480.7 5498.7 3347.7 3415.4 3486.8 3516.6 3852.6 3473.4 3729.6 3985.9 4207.6 4247.6 4222.5 4230.7 4235.7 4251.6 4253.8 3982.3 4159.9 4203.5 4229.5 4236.7 3106.8 3403.7 3618.0 3641.2 3641.2 4600.1 5282.9 5293.2 5296.1 5340.2 4267.6 4805.9 4822.8 4833.0 4842.3 5230.0 5286.3 5656.4 5889.3 5935.6 3501.3 3955.6 3963.6 3971.0 3985.4 4920.5 5967.1 6104.8 6123.9 6134.8 3704.2 3991.8 4005.1 4020.7 4033.5 4055.0 4077.4 4100.9 4103.7 4123.6 4667.0 4673.3 4725.4 4782.9 4791.2 5659.2 5883.7 5891.4 5904.7 5958.6 1126.9 1338.2 1382.5 1384.1 1392.7 745.2 801.2 834.5 855.7 857.1 4910.8 5101.6 5352.4 5489.8 5501.5 5552.1 5638.5 5755.5 5787.8 6097.3 4639.5 4691.4 4931.6 5510.9 5546.1 2528.6 3072.3 3183.4 3241.0 3253.8 4897.0 5760.4 6288.2 6347.3 6378.2 5820.7 5902.5 5979.6 6333.9 6370.9 6322.3 6330.2 6338.6 6345.6 6406.9 5446.9 6091.7 6093.3 6161.2 6181.3 5219.6 5250.6 5398.4 6014.8 6064.3 5857.9 6251.8 6288.9 6330.3 6353.8 3030.7 3122.7 3312.2 3465.9 3507.4 4327.5 4380.5 4506.3 4608.9 4868.6 4666.9 4734.8 4804.5 5058.3 5105.8 3854.9 4507.3 4609.0 4813.2 4823.9 4491.4 4506.9 4656.6 4904.6 4931.5 5500.7 5709.6 5834.8 5847.6 5870.9 5226.7 5354.0 5366.5 5770.0 5823.9 06:15:32 INFO - PROCESS | 1660 | 06:15:32 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:17956.89 Median:26920.03 stddev:24011.58 (89.2%) stddev-sans-first:24043.64 06:15:32 INFO - PROCESS | 1660 | Values: 617.0 716.2 721.1 821.5 853.9 14570.1 15194.5 15409.9 15455.5 15469.5 13714.5 14140.4 14150.3 14155.0 14162.9 702.1 879.4 879.8 888.1 896.3 902.7 906.2 906.4 907.2 907.5 802.5 941.9 951.5 951.7 954.8 798.7 919.0 939.3 939.6 946.4 685.4 695.9 700.8 702.6 703.8 12061.0 14458.8 14608.3 14768.0 14774.0 13082.6 14263.1 14267.3 14268.4 14330.3 11075.3 11853.9 11967.5 12201.4 12277.2 3814.5 3883.0 3894.9 3903.8 4024.3 79308.0 80212.6 80279.6 80597.2 84181.0 9545.6 9560.4 9621.9 9695.0 9966.2 19735.5 19741.4 19766.3 19782.0 19806.8 7568.8 7691.9 7780.2 7981.0 8146.8 3441.6 4485.1 4528.4 4552.0 4660.6 3526.3 3628.1 3633.3 3644.6 3651.6 700.0 737.5 745.8 755.8 757.8 586.4 691.4 698.8 707.6 709.4 8141.6 9111.2 9422.4 9528.0 9555.5 617.2 624.6 645.0 662.2 717.9 4267.9 4294.8 4502.0 4503.1 4595.9 54853.4 63676.0 96725.5 97845.5 98889.0 65759.3 65763.0 66500.0 77853.6 79921.0 73535.6 74274.6 74426.0 74687.0 75787.0 8838.8 9644.8 9671.2 9728.0 9761.0 43022.6 43698.9 46030.8 46090.5 46147.1 46126.0 49179.5 57625.4 58064.0 58472.8 12673.5 12799.8 12888.0 12897.9 13450.8 14316.6 14362.0 14374.9 14414.5 14649.9 13158.9 13461.5 13791.4 13951.7 13971.9 13262.3 13281.2 13358.2 13385.9 13549.7 12810.8 12844.7 12965.9 13171.2 13207.6 10767.5 11290.3 11293.4 11369.9 11391.3 10780.4 11155.5 11398.8 11426.5 11428.5 06:15:32 INFO - PROCESS | 1660 | -------- Summary: end -------- 06:15:32 INFO - PROCESS | 1660 | 06:15:32 INFO - TEST-INFO | 1660: exit 0 06:15:32 INFO - TEST-OK | dromaeo_css | took 1112478ms 06:15:32 INFO - TEST-START | kraken 06:15:32 INFO - operating with platform_type : mac_ 06:15:32 INFO - Initialising browser for kraken test... 06:15:32 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpLJCM8W/profile http://localhost:49275/getInfo.html) 06:15:35 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 06:15:35 INFO - PROCESS | 1671 | colorDepth:24 06:15:35 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 06:15:35 INFO - PROCESS | 1671 | __metrics 06:15:35 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 06:15:36 INFO - TEST-INFO | 1671: exit 0 06:15:36 INFO - Browser initialized. 06:15:36 INFO - Running cycle 1/1 for kraken test... 06:15:36 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpLJCM8W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:15:48 INFO - PROCESS | 1673 | RSS: Main: 252538880 06:15:48 INFO - PROCESS | 1673 | 06:15:51 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 06:15:52 INFO - PROCESS | 1673 | RSS: Main: 265449472 06:15:52 INFO - PROCESS | 1673 | 06:15:54 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 06:15:55 INFO - PROCESS | 1673 | RSS: Main: 269791232 06:15:55 INFO - PROCESS | 1673 | 06:15:58 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 06:15:59 INFO - PROCESS | 1673 | RSS: Main: 270413824 06:15:59 INFO - PROCESS | 1673 | 06:16:01 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 06:16:02 INFO - PROCESS | 1673 | RSS: Main: 271233024 06:16:02 INFO - PROCESS | 1673 | 06:16:04 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:16:04 INFO - PROCESS | 1673 | RSS: Main: 271835136 06:16:04 INFO - PROCESS | 1673 | 06:16:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 06:16:08 INFO - PROCESS | 1673 | RSS: Main: 271822848 06:16:08 INFO - PROCESS | 1673 | 06:16:11 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 06:16:11 INFO - PROCESS | 1673 | RSS: Main: 272076800 06:16:11 INFO - PROCESS | 1673 | 06:16:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 06:16:15 INFO - PROCESS | 1673 | RSS: Main: 272654336 06:16:15 INFO - PROCESS | 1673 | 06:16:16 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:16:17 INFO - PROCESS | 1673 | RSS: Main: 271585280 06:16:17 INFO - PROCESS | 1673 | 06:16:19 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:16:20 INFO - PROCESS | 1673 | RSS: Main: 271777792 06:16:20 INFO - PROCESS | 1673 | 06:16:22 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:16:23 INFO - PROCESS | 1673 | RSS: Main: 272162816 06:16:23 INFO - PROCESS | 1673 | 06:16:26 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:16:26 INFO - PROCESS | 1673 | RSS: Main: 272240640 06:16:26 INFO - PROCESS | 1673 | 06:16:29 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:16:30 INFO - PROCESS | 1673 | RSS: Main: 272400384 06:16:30 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 06:16:32 INFO - PROCESS | 1673 | RSS: Main: 273207296 06:16:32 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | __start_tp_report 06:16:32 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 06:16:32 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 06:16:32 INFO - PROCESS | 1673 | |i|pagename|runs| 06:16:32 INFO - PROCESS | 1673 | |0;ai-astar;118;108;116;115;117;115;116;116;116;115 06:16:32 INFO - PROCESS | 1673 | |1;audio-beat-detection;160;123;172;193;122;161;162;123;167;132 06:16:32 INFO - PROCESS | 1673 | |2;audio-dft;194;200;203;215;215;197;228;291;238;194 06:16:32 INFO - PROCESS | 1673 | |3;audio-fft;71;82;75;142;59;57;59;70;157;59 06:16:32 INFO - PROCESS | 1673 | |4;audio-oscillator;72;97;160;97;79;75;113;82;92;140 06:16:32 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;113;87;88;79;126;93;92;128;90;88 06:16:32 INFO - PROCESS | 1673 | |6;imaging-darkroom;96;105;135;96;95;96;97;95;95;96 06:16:32 INFO - PROCESS | 1673 | |7;imaging-desaturate;91;77;155;150;91;91;90;90;92;92 06:16:32 INFO - PROCESS | 1673 | |8;json-parse-financial;58;56;59;59;59;58;58;58;59;56 06:16:32 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;71;70;74;65;93;115;89;98;98;100 06:16:32 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;88;89;89;87;86;88;87;93;127;108 06:16:32 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;153;153;134;152;159;146;128;146;151;151 06:16:32 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;168;170;168;165;203;217;227;171;163;169 06:16:32 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;60;86;86;83;83;82;68;68;90;62 06:16:32 INFO - PROCESS | 1673 | __end_tp_report 06:16:32 INFO - PROCESS | 1673 | __start_cc_report 06:16:32 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1087 06:16:32 INFO - PROCESS | 1673 | __end_cc_report 06:16:32 INFO - PROCESS | 1673 | __startTimestamp1464614192380__endTimestamp 06:16:32 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | ------- Summary: start ------- 06:16:32 INFO - PROCESS | 1673 | Number of tests: 14 06:16:32 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:115.20 Median:116.00 stddev:2.70 (2.3%) stddev-sans-first:2.67 06:16:32 INFO - PROCESS | 1673 | Values: 118.0 108.0 116.0 115.0 117.0 115.0 116.0 116.0 116.0 115.0 06:16:32 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:151.50 Median:161.50 stddev:24.79 (15.3%) stddev-sans-first:26.10 06:16:32 INFO - PROCESS | 1673 | Values: 160.0 123.0 172.0 193.0 122.0 161.0 162.0 123.0 167.0 132.0 06:16:32 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:217.50 Median:215.00 stddev:29.79 (13.9%) stddev-sans-first:30.36 06:16:32 INFO - PROCESS | 1673 | Values: 194.0 200.0 203.0 215.0 215.0 197.0 228.0 291.0 238.0 194.0 06:16:32 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:83.10 Median:64.50 stddev:36.12 (56.0%) stddev-sans-first:38.04 06:16:32 INFO - PROCESS | 1673 | Values: 71.0 82.0 75.0 142.0 59.0 57.0 59.0 70.0 157.0 59.0 06:16:32 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:100.70 Median:80.50 stddev:29.10 (36.1%) stddev-sans-first:28.95 06:16:32 INFO - PROCESS | 1673 | Values: 72.0 97.0 160.0 97.0 79.0 75.0 113.0 82.0 92.0 140.0 06:16:32 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:98.40 Median:88.00 stddev:17.37 (19.7%) stddev-sans-first:17.60 06:16:32 INFO - PROCESS | 1673 | Values: 113.0 87.0 88.0 79.0 126.0 93.0 92.0 128.0 90.0 88.0 06:16:32 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:100.60 Median:96.00 stddev:12.45 (13.0%) stddev-sans-first:13.09 06:16:32 INFO - PROCESS | 1673 | Values: 96.0 105.0 135.0 96.0 95.0 96.0 97.0 95.0 95.0 96.0 06:16:32 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:101.90 Median:91.00 stddev:27.06 (29.7%) stddev-sans-first:28.41 06:16:32 INFO - PROCESS | 1673 | Values: 91.0 77.0 155.0 150.0 91.0 91.0 90.0 90.0 92.0 92.0 06:16:32 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:58.50 stddev:1.15 (2.0%) stddev-sans-first:1.22 06:16:32 INFO - PROCESS | 1673 | Values: 58.0 56.0 59.0 59.0 59.0 58.0 58.0 58.0 59.0 56.0 06:16:32 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:87.30 Median:81.50 stddev:16.44 (20.2%) stddev-sans-first:16.34 06:16:32 INFO - PROCESS | 1673 | Values: 71.0 70.0 74.0 65.0 93.0 115.0 89.0 98.0 98.0 100.0 06:16:32 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:94.20 Median:88.00 stddev:13.21 (15.0%) stddev-sans-first:13.82 06:16:32 INFO - PROCESS | 1673 | Values: 88.0 89.0 89.0 87.0 86.0 88.0 87.0 93.0 127.0 108.0 06:16:32 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:151.50 stddev:9.45 (6.2%) stddev-sans-first:9.80 06:16:32 INFO - PROCESS | 1673 | Values: 153.0 153.0 134.0 152.0 159.0 146.0 128.0 146.0 151.0 151.0 06:16:32 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.10 Median:170.50 stddev:23.96 (14.1%) stddev-sans-first:24.86 06:16:32 INFO - PROCESS | 1673 | Values: 168.0 170.0 168.0 165.0 203.0 217.0 227.0 171.0 163.0 169.0 06:16:32 INFO - PROCESS | 1673 | 06:16:32 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.80 Median:83.00 stddev:11.07 (13.3%) stddev-sans-first:9.94 06:16:32 INFO - PROCESS | 1673 | Values: 60.0 86.0 86.0 83.0 83.0 82.0 68.0 68.0 90.0 62.0 06:16:32 INFO - PROCESS | 1673 | -------- Summary: end -------- 06:16:32 INFO - PROCESS | 1673 | 06:16:33 INFO - TEST-INFO | 1673: exit 0 06:16:33 INFO - TEST-OK | kraken | took 60379ms 06:16:33 INFO - SUITE-END | took 1172s 06:16:33 INFO - Completed test suite (00:19:33) 06:16:33 INFO - javascript benchmark 06:16:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7288.239489536134, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3244.334911343175, 3500.4749287606874, 3512.718982481402, 3557.9644203557955, 3571.71426285897, 5339.06566350888, 5622.465865742748, 5848.152017957394, 5907.5864689471755, 5949.286085669721, 5750.482456578907, 5879.88240235195, 5968.522518198533, 6016.7593296268205, 6019.217501724781, 5238.926020165866, 5298.470152984709, 5405.162199859008, 5450.29146210992, 5475.561955043587, 122.53010417433472, 5312.893742125155, 5320.095583750753, 5388.568914486851, 5418.512333889949, 5322.866928326804, 5352.652077614962, 5363.195520671891, 5368.651037682538, 5405.243265942771, 5311.521963023347, 5427.674339539641, 5453.590980676426, 5459.563234941234, 5476.315460567429, 5296.841094767163, 5317.12267475866, 5350.678959262456, 5364.329458817648, 5395.865103372408, 5797.275340582427, 5942.019566771521, 5948.0, 5972.044472884318, 5990.64056156632, 4781.378420805273, 5950.928832810093, 6270.216222972129, 6275.7489700411925, 6278.497720182374, 5741.368449470555, 6355.0, 6356.364363563606, 6370.0, 6434.388733070351, 6076.5442591805795, 6118.816435506942, 6125.326214116443, 6129.540284478593, 6143.907841382383, 6267.059941008794, 6311.053341998645, 6316.589421687576, 6450.548461607643, 6458.321876203025, 6201.441870231701, 6244.781432649836, 6266.342034086447, 6283.120363149163, 6306.211723534559, 6218.253809542884, 6282.811515654537, 6338.746450141943, 6350.015747559136, 6375.744970201233, 6152.0, 6192.783252586138, 6193.4425901669065, 6290.308066112724, 6294.748210071637, 6208.0, 6209.720562574695, 6229.345918678474, 6230.937690623035, 6261.686915654199, 5063.974680126649, 5082.2122571001555, 5120.974395128075, 5121.411037730634, 5128.692278463304, 4514.435695538058, 4519.231730605805, 4526.841560545365, 4539.568740969603, 4647.488776234612, 5241.42344342122, 5789.0, 6014.699265036818, 6020.367861374492, 6038.184845045863, 5096.719680417613, 6157.2611286645, 6292.716827742763, 6324.620522768649, 6342.460890824239, 5840.29916410025, 6001.759929602855, 6041.093835924558, 6080.179175811298, 6096.542759293071, 5553.972230138905, 5582.944170558324, 5587.860303492445, 5592.384837667853, 5600.103983362643, 6326.905096423649, 6555.868882622321, 6589.80230593083, 6605.438537724252, 6617.272100068989, 4654.953450465452, 6244.718987645476, 6272.8118156455375, 6335.429811317003, 6344.302126766144, 6503.2846386898345, 6539.738410463528, 6541.8691626166255, 6551.246606640298, 6597.835054123685, 6104.186323682738, 6120.904292401297, 6291.307956124914, 6298.905516417349, 6309.558330916791, 6184.721687524162, 6353.841153971188, 6356.300806911144, 6365.777197798055, 6367.458766004756, 5219.556337711337, 5437.32033495813, 5599.99200143978, 6158.815235542941, 6312.779052733133, 6091.177691011657, 6210.720517576629, 6220.222472190976, 6319.368063193644, 6319.778807741707, 5589.329280486368, 5661.915071274017, 5666.291713535808, 5721.771129154954, 5993.460588547052, 5851.590388672752, 5903.557233207441, 5924.31870334917, 5924.31870334917, 5944.0, 5790.1893734877995, 5793.884122317446, 6035.909461358171, 6080.087986801838, 6097.664628445301], "value": 5740.322224023947, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.87097483679406, 502.21403503516996, 514.3031192733846, 546.9962618594878, 557.5316733943488, 37265.0, 40856.0, 40865.59134408655, 40877.0, 40914.0, 33545.496817547755, 40429.0, 40668.38997415034, 41054.794726026405, 41083.58916410835, 9478.81042379152, 9711.951440242825, 9729.805403891953, 9731.026897310283, 9750.512474376288, 8378.916210837875, 9191.264698824078, 9431.830678943847, 9451.669191578296, 9514.429134251932, 8188.385871059664, 9482.288828337867, 9493.0, 9523.523823808817, 9549.0, 8198.303144232717, 9441.386309889904, 9451.858222126673, 9526.856777186713, 9545.236381089566, 9475.052494750538, 9554.331196816225, 9581.233501319877, 9607.0, 9607.375520591138, 32673.0, 39047.0, 39221.0, 39434.60565394338, 39453.40819887675, 3107.9533806992913, 3350.27968986669, 3370.8314584270934, 3407.16524451511, 3551.2187318790084, 29675.406491870257, 29787.55318670222, 30375.696243037506, 30653.69346306553, 30828.38343233123, 3138.905832825019, 3144.24538110854, 3144.3868445653106, 3165.6359518655418, 3269.820159891205, 357861.0, 408914.0, 414754.0, 414784.9260753707, 415016.0, 32113.51829722556, 32298.83850580779, 32442.864499742394, 32456.02631921046, 32495.51256731151, 34178.0, 34473.482897756556, 34517.65482345194, 35597.0, 35692.10769730777, 16497.422590209284, 16572.917135414245, 16676.916615416845, 16677.749833752503, 16683.332666693197, 12368.505259789688, 12413.813792793115, 12755.107142500121, 12860.935695321463, 12878.67803304925, 16271.51185464438, 16393.754093688603, 19618.50953726168, 19650.21399144047, 19766.90116549408, 8306.54314012723, 8477.364197685198, 8479.618417171243, 8480.915190848013, 8520.531370774543, 890.9910900890908, 973.956171972263, 992.1467537917385, 999.3903718731568, 1039.719275795531, 30351.392972140435, 30458.69541304603, 30506.54240186399, 30558.0, 30720.69279307223, 1048.8846226915034, 1069.4759567811716, 1087.3693257910393, 1147.7532330548804, 1198.4846515998204, 14895.404183832525, 15801.604959875865, 15998.520044398687, 16047.919760401357, 16268.51194464168, 639470.0, 645133.0, 653225.0, 653316.0, 654099.0, 57149.0, 59710.0, 59780.0, 59812.40187598155, 59896.70051649714, 1410.3512384303335, 1420.7584710599224, 1432.44134787433, 1434.9210793406462, 1440.7262620102147, 30026.0, 30070.744694148463, 30381.696183037886, 31105.68894311028, 31549.8422507886, 57791.0, 58511.82976340364, 58517.0, 58627.70686146542, 58725.0, 592366.0, 614747.0, 614762.9261853662, 623202.0, 625835.0, 44009.0, 44262.55737442584, 44488.88777780452, 44883.55116448794, 45081.323780142666, 46915.0, 47898.76050619724, 48111.27833082437, 48192.036159275915, 49339.0, 35704.64295357013, 37801.243975120895, 39638.60361396349, 40922.0, 41631.58368416398, 35945.1013724659, 38606.80696596499, 38642.0, 40630.79684601558, 40752.388714168716, 32601.673983259865, 38597.80701096477, 38628.034299141626, 39287.0, 39433.60566394414, 33789.66210337865, 36843.0, 40733.0, 42660.78669606632, 44500.55499444964, 31386.372272553963, 31832.045038648877, 32385.0, 33005.33989320152, 33452.832735836164], "value": 19871.8039037726, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.7181184581947, 374.2048147686167, 377.2963423215702, 382.17640983680167, 397.4931961748772, 19252.807471925276, 20648.0, 20863.791362086373, 21067.47331316718, 21291.467713307135, 20749.688754668692, 21362.786372136274, 21440.0, 21717.131314747392, 21824.23615173469, 5386.761044959661, 5922.555808314372, 6140.171076904628, 6174.259088909337, 6186.783462578811, 5884.352721200665, 6109.755609775603, 6133.6626485543275, 6175.505959523227, 6176.752929882799, 5992.610480318787, 6015.308239552463, 6016.849578760544, 6019.518438524908, 6021.036634138539, 5132.461091585405, 5722.885542289173, 5995.220621319222, 5996.610220335686, 6023.6988150592515, 5183.0, 5232.92150617741, 5237.528622424, 5286.365636123652, 5290.65610735301, 14395.568132956028, 20841.478963026046, 22145.33563993083, 22619.77380226193, 23607.0, 1974.1412485568824, 2290.404494831339, 2306.423394151462, 2350.224425939436, 2457.6559281700575, 9821.852672209923, 9987.600495980152, 10295.742606434898, 10504.894951050544, 16087.597810054725, 1870.681984062699, 1923.4422017614875, 1944.8346890514324, 1952.0630097553253, 1957.765068191812, 78430.60784696096, 101789.0, 109078.0, 116155.0, 121598.39200804027, 9538.0, 11521.539138434537, 11797.764044719057, 11802.704932376759, 11970.982466490272, 14789.704205915821, 14839.554813355617, 14842.0, 14855.628609284855, 14882.925585372004, 8546.829063418696, 8748.781280467912, 8749.993750718622, 8833.14503954164, 8835.0, 8150.225728555778, 8529.189726975927, 8640.006399264039, 8746.34402419821, 8755.0807165248, 4469.754163521037, 4626.352310676508, 4629.814807407666, 4667.393238878924, 4688.015516741454, 1686.8819182657096, 1943.416974907522, 1971.6648169811167, 1974.2952155546993, 1975.7530308711412, 849.1211595998112, 947.2901412949554, 983.5721461164417, 1066.861308029951, 1067.4182570498942, 20400.97995100239, 20957.580848382946, 21096.894515527325, 21144.682829757567, 21206.363809085753, 950.1543626172712, 950.2303134461108, 962.4225464721111, 970.4419958523736, 982.5627595719978, 10664.840027399596, 11107.722306942229, 11301.152413569016, 11326.773464530828, 11350.48922798476, 604090.0, 604542.0, 613734.0, 615504.0, 619108.0, 122037.38981305037, 122413.0, 123254.38372808078, 123467.0, 127660.0, 966.8501382285757, 982.5578489679673, 1005.6882366466418, 1006.104566935428, 1009.8384258518602, 18773.6245275091, 18863.0, 18929.526761831065, 19127.52181195481, 19377.224911003403, 50108.24837627366, 50939.74530127326, 52021.47978520166, 52307.0, 52359.47640523546, 55153.0, 69694.65152674205, 71731.56536869338, 75225.34908485594, 79356.20643793719, 6830.21452532965, 7283.688935991572, 7658.119316278699, 8157.388195885451, 8285.668573257244, 10331.84502232452, 12348.0, 12645.620631381073, 12879.549215777402, 12932.094753367173, 10888.67333979982, 10889.455527223765, 10932.180086493387, 11011.174161937726, 11263.098952083978, 9621.326507144433, 9797.06927841854, 10603.363798172135, 10834.07910327631, 11019.504122314354, 10752.0, 11341.432928353714, 11382.772344553228, 11416.771664566828, 11704.005159561533, 8089.191080891864, 8122.75631731049, 8128.674853005814, 8254.83490330178, 8318.085010648945, 10826.004620311562, 11692.94153529227, 11862.940685296518, 11921.94039029834, 11954.342511161969], "value": 9531.145543380464, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1311.3508813137496, 1576.4797616786461, 1749.9737503937436, 1897.77226732792, 1909.3508207209545, 8046.436749427542, 8615.784605384868, 8630.266427353683, 9090.63637454501, 9117.680881169152, 8724.8691269631, 8808.515531645757, 8874.068222836606, 8955.552222388871, 8966.0, 2361.161787565415, 2381.7261014983324, 2385.594448943675, 2386.093284551868, 2386.868722220277, 2383.6305372667266, 2389.7251816041116, 2404.290734233405, 2405.4707964247837, 2425.5876500994873, 2403.9158629447975, 2405.0379848060743, 2405.36257891658, 2411.7829395354415, 2419.080749315258, 2374.5725769361507, 2389.534040862024, 2393.640953856918, 2406.8796560171922, 2411.0717373811035, 2349.835511514195, 2357.233898982837, 2357.481354102095, 2358.8466749661216, 2359.858408495496, 8782.824343513157, 8878.600462979182, 8914.955425222897, 8926.196642302224, 8990.28077753778, 4756.500567440404, 5251.264822924793, 5333.333333333333, 5438.184272359138, 5451.373092094381, 3250.333681595267, 3319.767616266862, 3565.073080998933, 3629.0927268182954, 3731.962680373216, 962.7833737409027, 1000.2298230362653, 1011.9139873110795, 1107.800595892739, 1111.372074777748, 10703.518341674644, 10720.303180293256, 11581.942090289494, 11606.187566870323, 11610.70973225676, 4728.550787675165, 4737.265723812815, 4802.6638135330195, 4861.368022157097, 4863.9513604864205, 9395.342326037111, 9446.102620251066, 9840.163586095263, 9915.058069483388, 9925.057119573628, 5318.388385335736, 5355.464453554613, 5417.404085550586, 5418.106012507892, 5425.647332923348, 4439.755813430293, 4829.806807727651, 4832.251001094836, 4918.2376731606655, 4944.554990050912, 4053.3717273822604, 4060.4979083169787, 4070.247004304213, 4073.918521629551, 4089.2026054919006, 1295.4688577683105, 1311.2591385866954, 1317.985151433391, 1324.1723922548408, 1330.2750001249342, 854.1159899503983, 889.1597440418742, 932.8572498689051, 937.5124935033745, 940.4827344960245, 5913.58604897653, 5991.820245392645, 6005.669688167192, 6089.5737298388685, 6211.223597050369, 778.4978688745728, 782.460102529253, 833.2334252487726, 900.7612982559627, 996.3125443444001, 3338.582677165354, 3934.7639141651593, 3966.2265858157957, 4026.53694825093, 4042.0, 10476.319039262577, 10840.349579025125, 10890.509927053301, 10954.178436617285, 10982.780344393068, 10954.890451095547, 10968.451577421098, 10974.615888443866, 10978.451077446256, 10980.780384392267, 7924.564148971746, 8244.711435099864, 8326.750197494084, 8329.750107496784, 8360.289375403037, 5727.455891690282, 5879.73541190656, 5906.084556893688, 5934.228550288435, 5982.49148822355, 10088.596456141671, 10103.545340459845, 10190.796184076426, 10193.898061019296, 10252.897471025195, 9963.25275604318, 10191.84712229332, 10197.9490102549, 10400.635977740743, 10422.426766527611, 6622.304658010839, 6849.212340580897, 7029.402500787491, 7129.0, 7294.379977701955, 8508.574571271536, 8521.44610600309, 8526.488410695378, 8608.268297194558, 8615.181557752003, 7482.925170748223, 7669.271419215165, 7682.769516914502, 7707.0, 7759.650815713419, 7335.816604584928, 7412.777616671508, 7463.962680186564, 7492.325690687864, 7644.15914249421, 7035.261297563682, 7108.324709152622, 7127.679254433667, 7157.0, 7157.962095496228, 4466.709663871838, 4899.559039686303, 4943.802247910187, 5058.418281897481, 5113.335266415343, 5043.142665746906, 5078.085944529872, 5086.949130508648, 5126.512981266774, 5194.714290713896], "value": 4617.634947653173, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.62438512821797, 310.74518894506514, 311.5342562868512, 316.9793963392378, 318.95694081299047, 5551.972240138799, 5601.495865372116, 5647.5764317676185, 5652.689102099388, 5656.490915817576, 4701.576858082772, 5475.808346707866, 5476.0, 5480.698561579112, 5498.697571633558, 3347.698707116359, 3415.4023045966856, 3486.825658717054, 3516.593362654946, 3852.6339997700316, 3473.392156372637, 3729.5710993235716, 3985.940210896839, 4207.579242075783, 4247.638950689196, 4222.493300803908, 4230.688486569158, 4235.703500754948, 4251.638610718077, 4253.787310634456, 3982.283189025974, 4159.9376009359885, 4203.495580530325, 4229.534751177368, 4236.745795252279, 3106.829124398157, 3403.710684591813, 3618.028174644825, 3641.1625326175126, 3641.18073433478, 4600.07998400318, 5282.947170528284, 5293.179557168645, 5296.099663057251, 5340.225667278261, 4267.551907049778, 4805.855824325241, 4822.758862056919, 4832.9609134036, 4842.346283251751, 5230.006298803253, 5286.286351342558, 5656.444477768647, 5889.263842019747, 5935.643861368331, 3501.3347463981763, 3955.564887862333, 3963.573113679102, 3971.007248187953, 3985.3822657488136, 4920.507949205086, 5967.060705270761, 6104.825490744833, 6123.9387606124255, 6134.785282515135, 3704.240630670706, 3991.7604943703473, 4005.136327525833, 4020.6783457323345, 4033.51597808259, 4054.959450405458, 4077.4495443115393, 4100.876973690794, 4103.692223083281, 4123.60825721556, 4667.0, 4673.299005149255, 4725.385699858996, 4782.876024134325, 4791.185498465269, 5659.179418984307, 5883.7058147092475, 5891.351951285355, 5904.675242861684, 5958.553108516879, 1126.8929451702074, 1338.2305174524688, 1382.523029554802, 1384.1279993604167, 1392.6839137015422, 745.1505283976271, 801.2468279816953, 834.5284914023557, 855.7347222361088, 857.1428571428571, 4910.772306923269, 5101.642884998088, 5352.357717073976, 5489.807856724996, 5501.477359650827, 5552.140647566506, 5638.520725738276, 5755.539556835443, 5787.75885504119, 6097.298810636834, 4639.489656137822, 4691.413573303337, 4931.608170037529, 5510.9448905510435, 5546.140348246028, 2528.633348164506, 3072.339447018902, 3183.4428974929297, 3241.0067808297727, 3253.7711667507833, 4896.971635956418, 5760.3951585083805, 6288.182536270256, 6347.301796802348, 6378.24598235489, 5820.650760954356, 5902.498287645598, 5979.634866547358, 6333.936660633335, 6370.904436433457, 6322.272938612026, 6330.208723909512, 6338.556301058881, 6345.619262844244, 6406.871862562816, 5446.891062178814, 6091.695415229309, 6093.29927058394, 6161.168242287234, 6181.289151747426, 5219.582433405242, 5250.579953603779, 5398.352197736297, 6014.789482368096, 6064.332923378513, 5857.853553661193, 6251.843703907439, 6288.874222515616, 6330.272018717907, 6353.77761778354, 3030.727234548813, 3122.703343182394, 3312.2381852173653, 3465.86136554535, 3507.3511400391008, 4327.545607711271, 4380.451834014459, 4506.301523263899, 4608.949017687097, 4868.63678170113, 4666.929996049994, 4734.834280800155, 4804.5195480451675, 5058.266551350107, 5105.846824595268, 3854.8843534694006, 4507.346434767006, 4608.986023074918, 4813.229883218668, 4823.927641085456, 4491.438570178728, 4506.90986180268, 4656.627469802475, 4904.553156818675, 4931.457539670705, 5500.669959802424, 5709.629688874557, 5834.795782147605, 5847.619904706181, 5870.9412905870395, 5226.686398816083, 5354.009508240988, 5366.543843773166, 5770.0190967536455, 5823.912641310299], "value": 4115.832535237315, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [617.0127795527155, 716.1907045039105, 721.0656298662014, 821.511200835503, 853.9274161696255, 14570.12579245246, 15194.468193613198, 15409.922950385246, 15455.45905893294, 15469.535913922586, 13714.519991800289, 14140.434382624682, 14150.292485375741, 14155.0, 14162.858371416283, 702.0627462337785, 879.3536750488386, 879.7668617816252, 888.1473785166248, 896.2964073202537, 902.6660135749781, 906.1934877958622, 906.4289497616526, 907.2469850024497, 907.4555266839876, 802.546561192927, 941.8963913969458, 951.5004622573169, 951.6621599332241, 954.7613096725819, 798.6845196147489, 918.9908100919039, 939.2908354192575, 939.6147579492375, 946.4037656276536, 685.3626127701236, 695.8538706871564, 700.7617410080597, 702.5889854435162, 703.8416356319788, 12061.0, 14458.783118253235, 14608.269586520631, 14768.0, 14774.039687420283, 13082.638151825691, 14263.144211347353, 14267.286635668175, 14268.357923893345, 14330.283485825667, 11075.280106793032, 11853.88146118545, 11967.461464234131, 12201.389930503528, 12277.20198187115, 3814.5041144651295, 3882.9611703883165, 3894.8665379684594, 3903.7657740535374, 4024.2555127301544, 79308.0, 80212.59893700553, 80279.5986020072, 80597.19402805956, 84181.0, 9545.570449329796, 9560.378575392577, 9621.855672164924, 9694.95152524247, 9966.15287700539, 19735.506612334808, 19741.40775776729, 19766.308179213658, 19782.0, 19806.801931980495, 7568.772936811904, 7691.884676721844, 7780.221977802287, 7980.960095199603, 8146.796330091676, 3441.63862794408, 4485.102979404132, 4528.41130653013, 4551.95448045522, 4660.557247061523, 3526.3123690880034, 3628.1473853644375, 3633.3096711625235, 3644.580873199616, 3651.6165802590876, 699.9860002799916, 737.5095561451682, 745.7725393754957, 755.845051764398, 757.7802437293234, 586.3813676571173, 691.408845437154, 698.8329489752025, 707.6002058836843, 709.4359983812881, 8141.552214628252, 9111.225545828545, 9422.387544809566, 9527.952360238154, 9555.474448905377, 617.2286433911704, 624.5877720704405, 644.9970296189471, 662.1722846441947, 717.9210286868545, 4267.935980960288, 4294.849680261176, 4502.009557897258, 4503.144402563503, 4595.862124136282, 54853.35439936808, 63676.0, 96725.5163724191, 97845.5107724471, 98889.0, 65759.34240657532, 65763.0, 66500.0, 77853.61073194598, 79921.0, 73535.63232183912, 74274.62862685652, 74426.0, 74687.0, 75787.0, 8838.823223535364, 9644.75888102803, 9671.226301895973, 9728.0, 9761.0, 43022.569774303105, 43698.90752731207, 46030.84922876955, 46090.539094608626, 46147.07705845931, 46126.0, 49179.508204917496, 57625.423745762004, 58064.0, 58472.830543388045, 12673.493060277855, 12799.80800288015, 12888.0, 12897.871021289668, 13450.798238026633, 14316.57050288493, 14362.0, 14374.856251437352, 14414.495492657707, 14649.92675036618, 13158.934205328913, 13461.528846490326, 13791.448342066205, 13951.651208719863, 13971.860281397056, 13262.27057511846, 13281.203127812361, 13358.198508089547, 13385.933070334586, 13549.729005420033, 12810.807837882252, 12844.743105138032, 12965.870341296844, 13171.209727416002, 13207.60377188686, 10767.461626918779, 11290.322580644859, 11293.378864162221, 11369.886301137214, 11391.316521008766, 10780.353178809297, 11155.457892979897, 11398.829017564578, 11426.485808138488, 11428.485718142538], "value": 7252.61905506521, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1614.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 108.0, 116.0, 115.0, 117.0, 115.0, 116.0, 116.0, 116.0, 115.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 123.0, 172.0, 193.0, 122.0, 161.0, 162.0, 123.0, 167.0, 132.0], "value": 151.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 200.0, 203.0, 215.0, 215.0, 197.0, 228.0, 291.0, 238.0, 194.0], "value": 217.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 82.0, 75.0, 142.0, 59.0, 57.0, 59.0, 70.0, 157.0, 59.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 97.0, 160.0, 97.0, 79.0, 75.0, 113.0, 82.0, 92.0, 140.0], "value": 100.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 87.0, 88.0, 79.0, 126.0, 93.0, 92.0, 128.0, 90.0, 88.0], "value": 98.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 105.0, 135.0, 96.0, 95.0, 96.0, 97.0, 95.0, 95.0, 96.0], "value": 100.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 77.0, 155.0, 150.0, 91.0, 91.0, 90.0, 90.0, 92.0, 92.0], "value": 101.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 59.0, 59.0, 59.0, 58.0, 58.0, 58.0, 59.0, 56.0], "value": 58.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 74.0, 65.0, 93.0, 115.0, 89.0, 98.0, 98.0, 100.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 89.0, 87.0, 86.0, 88.0, 87.0, 93.0, 127.0, 108.0], "value": 94.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 134.0, 152.0, 159.0, 146.0, 128.0, 146.0, 151.0, 151.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 168.0, 165.0, 203.0, 217.0, 227.0, 171.0, 163.0, 169.0], "value": 182.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 86.0, 86.0, 83.0, 83.0, 82.0, 68.0, 68.0, 90.0, 62.0], "value": 76.8, "unit": "score"}]}]} 06:16:33 INFO - Return code: 0 06:16:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:16:33 ERROR - # TBPL SUCCESS # 06:16:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:16:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:16:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:16:33 INFO - 2016-05-30 06:16:33.711 screenresolution[1678:10225] starting screenresolution argv=screenresolution get 06:16:33 INFO - 2016-05-30 06:16:33.713 screenresolution[1678:10225] Display 0: 1600x1200x32@60 06:16:33 INFO - 2016-05-30 06:16:33.719 screenresolution[1679:10228] starting screenresolution argv=screenresolution list 06:16:33 INFO - Available Modes on Display 0 06:16:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:16:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:16:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:16:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:16:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:16:33 INFO - Intel Iris: 06:16:33 INFO - Chipset Model: Intel Iris 06:16:33 INFO - Type: GPU 06:16:33 INFO - Bus: Built-In 06:16:33 INFO - VRAM (Dynamic, Max): 1536 MB 06:16:33 INFO - Vendor: Intel (0x8086) 06:16:33 INFO - Device ID: 0x0a2e 06:16:33 INFO - Revision ID: 0x0009 06:16:33 INFO - Displays: 06:16:33 INFO - Display: 06:16:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:16:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:16:33 INFO - Display Serial Number: 1600x1200 60 06:16:33 INFO - Main Display: Yes 06:16:33 INFO - Mirror: Off 06:16:33 INFO - Online: Yes 06:16:33 INFO - Rotation: Supported 06:16:33 INFO - Return code: 0 06:16:33 INFO - Running post-action listener: _resource_record_post_action 06:16:33 INFO - ##### 06:16:33 INFO - ##### Finished run-tests step (success) 06:16:33 INFO - ##### 06:16:33 INFO - Running post-run listener: _resource_record_post_run 06:16:34 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 38000128; Write bytes: 698061824; Read time: 392; Write time: 1965 06:16:34 INFO - TinderboxPrint: CPU usage
54.6% 06:16:34 INFO - TinderboxPrint: I/O read bytes / time
38,000,128 / 392 06:16:34 INFO - TinderboxPrint: I/O write bytes / time
698,061,824 / 1,965 06:16:34 INFO - TinderboxPrint: CPU idle
2,196.7 (45.4%) 06:16:34 INFO - TinderboxPrint: CPU system
973.7 (20.1%) 06:16:34 INFO - TinderboxPrint: CPU user
1,667.3 (34.5%) 06:16:34 INFO - TinderboxPrint: Swap in / out
435,904,512 / 0 06:16:34 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 393939456; Write bytes: 388052992; Read time: 29515; Write time: 688 06:16:34 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 36173312; Write bytes: 296655872; Read time: 371; Write time: 1240 06:16:34 INFO - Running post-run listener: _upload_blobber_files 06:16:34 INFO - Blob upload gear active. 06:16:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:16:34 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:16:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:16:34 INFO - (blobuploader) - INFO - Open directory for files ... 06:16:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:16:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:16:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:16:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:16:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:16:36 INFO - (blobuploader) - INFO - Done attempting. 06:16:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:16:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:16:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:16:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:16:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:16:37 INFO - (blobuploader) - INFO - Done attempting. 06:16:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:16:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:16:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:16:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:16:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:16:39 INFO - (blobuploader) - INFO - Done attempting. 06:16:39 INFO - (blobuploader) - INFO - Iteration through files over. 06:16:39 INFO - Return code: 0 06:16:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:16:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:16:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef5e7f3938fd477dd61ae21d05a21c4ebac1e404cd0e071db8fca02d5e6612328369279fda78b4792047402922149db52de4a0e41bf58a7132afa1fa11b4a3a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4f0f336b9973c1042157954cf60220386049dff3de303bcf8eeb081679b889d9c730727e9f5efc968f231db876332b208756f46dc1e6df0b878a1e6604da81b0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eeb99f9d8e8357623933113455a50e609b44d1a1c62c7c9090990f4b1ba7710bdd80a086d75eac9c0df0db04f19551f7ea2e85c7be1dedbb0585f9b4c82245e3"} 06:16:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:16:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:16:39 INFO - Contents: 06:16:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef5e7f3938fd477dd61ae21d05a21c4ebac1e404cd0e071db8fca02d5e6612328369279fda78b4792047402922149db52de4a0e41bf58a7132afa1fa11b4a3a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4f0f336b9973c1042157954cf60220386049dff3de303bcf8eeb081679b889d9c730727e9f5efc968f231db876332b208756f46dc1e6df0b878a1e6604da81b0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eeb99f9d8e8357623933113455a50e609b44d1a1c62c7c9090990f4b1ba7710bdd80a086d75eac9c0df0db04f19551f7ea2e85c7be1dedbb0585f9b4c82245e3"} 06:16:39 INFO - Running post-run listener: copy_logs_to_upload_dir 06:16:39 INFO - Copying logs to upload dir... 06:16:39 INFO - mkdir: /builds/slave/test/build/upload/logs 06:16:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.945352 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-30 06:16:39.393021) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 06:16:39.396125) ========= 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.ck6m1LIBZM/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.70RcoQMgB3/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef5e7f3938fd477dd61ae21d05a21c4ebac1e404cd0e071db8fca02d5e6612328369279fda78b4792047402922149db52de4a0e41bf58a7132afa1fa11b4a3a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4f0f336b9973c1042157954cf60220386049dff3de303bcf8eeb081679b889d9c730727e9f5efc968f231db876332b208756f46dc1e6df0b878a1e6604da81b0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eeb99f9d8e8357623933113455a50e609b44d1a1c62c7c9090990f4b1ba7710bdd80a086d75eac9c0df0db04f19551f7ea2e85c7be1dedbb0585f9b4c82245e3"} build_url:https://queue.taskcluster.net/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011092 build_url: 'https://queue.taskcluster.net/v1/task/aQ3duhoLSPaWtpaniGU-8g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef5e7f3938fd477dd61ae21d05a21c4ebac1e404cd0e071db8fca02d5e6612328369279fda78b4792047402922149db52de4a0e41bf58a7132afa1fa11b4a3a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4f0f336b9973c1042157954cf60220386049dff3de303bcf8eeb081679b889d9c730727e9f5efc968f231db876332b208756f46dc1e6df0b878a1e6604da81b0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eeb99f9d8e8357623933113455a50e609b44d1a1c62c7c9090990f4b1ba7710bdd80a086d75eac9c0df0db04f19551f7ea2e85c7be1dedbb0585f9b4c82245e3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 06:16:39.430159) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 06:16:39.430448) ========= 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.ck6m1LIBZM/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.70RcoQMgB3/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.004969 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 06:16:39.481298) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 06:16:39.482051) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 06:16:39.483377) ========= ========= Total master_lag: 0.17 =========