builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0285 starttime: 1464424810.11 results: success (0) buildid: 20160528004338 builduid: 51916248861840cc96bd23b7b83fbcb9 revision: 2e2c3435f648e36080c49c46416b710e0b52d55a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 01:40:10.112492) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 01:40:10.112877) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 01:40:10.113159) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 01:40:10.184965) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 01:40:10.185264) ========= 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.JWJxr50QVH/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.O8dIQaUOfs/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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-28 01:40:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-05-28 01:40:10 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.620771 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 01:40:10.824835) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 01:40:10.825128) ========= 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.JWJxr50QVH/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.O8dIQaUOfs/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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.025887 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 01:40:10.873287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-28 01:40:10.873595) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2e2c3435f648e36080c49c46416b710e0b52d55a --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 2e2c3435f648e36080c49c46416b710e0b52d55a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JWJxr50QVH/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.O8dIQaUOfs/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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-28 01:40:10,956 truncating revision to first 12 chars 2016-05-28 01:40:10,957 Setting DEBUG logging. 2016-05-28 01:40:10,957 attempt 1/10 2016-05-28 01:40:10,957 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2e2c3435f648?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-28 01:40:11,733 unpacking tar archive at: fx-team-2e2c3435f648/testing/mozharness/ program finished with exit code 0 elapsedTime=1.045775 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-28 01:40:11.941676) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 01:40:11.945024) ========= script_repo_revision: 2e2c3435f648e36080c49c46416b710e0b52d55a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 01:40:11.945436) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 01:40:11.945703) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 01:40:11.963797) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2016-05-28 01:40:11.964185) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JWJxr50QVH/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.O8dIQaUOfs/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 01:40:12 INFO - MultiFileLogger online at 20160528 01:40:12 in /builds/slave/test 01:40:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 01:40:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:40:12 INFO - {'append_to_log': False, 01:40:12 INFO - 'base_work_dir': '/builds/slave/test', 01:40:12 INFO - 'blob_upload_branch': 'Fx-Team', 01:40:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:40:12 INFO - 'branch': 'Fx-Team', 01:40:12 INFO - 'buildbot_json_path': 'buildprops.json', 01:40:12 INFO - 'config_files': ('talos/mac_config.py',), 01:40:12 INFO - 'default_actions': ('clobber', 01:40:12 INFO - 'read-buildbot-config', 01:40:12 INFO - 'download-and-extract', 01:40:12 INFO - 'populate-webroot', 01:40:12 INFO - 'create-virtualenv', 01:40:12 INFO - 'install', 01:40:12 INFO - 'run-tests'), 01:40:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:40:12 INFO - 'download_minidump_stackwalk': True, 01:40:12 INFO - 'download_symbols': 'ondemand', 01:40:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:40:12 INFO - 'tooltool.py': '/tools/tooltool.py', 01:40:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:40:12 INFO - '/tools/misc-python/virtualenv.py')}, 01:40:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:40:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:40:12 INFO - 'installer_path': 'installer.exe', 01:40:12 INFO - 'log_level': 'info', 01:40:12 INFO - 'log_name': 'talos', 01:40:12 INFO - 'log_to_console': True, 01:40:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:40:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:40:12 INFO - 'opt_config_files': (), 01:40:12 INFO - 'pip_index': False, 01:40:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:40:12 INFO - 'cmd': ('bash', 01:40:12 INFO - '-c', 01:40:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:40:12 INFO - 'enabled': True, 01:40:12 INFO - 'halt_on_failure': False, 01:40:12 INFO - 'name': 'check_screen_resolution'},), 01:40:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:40:12 INFO - 'cmd': ('bash', 01:40:12 INFO - '-c', 01:40:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:40:12 INFO - 'enabled': True, 01:40:12 INFO - 'halt_on_failure': False, 01:40:12 INFO - 'name': 'check_screen_resolution'},), 01:40:12 INFO - 'run_cmd_checks_enabled': True, 01:40:12 INFO - 'sps_profile': False, 01:40:12 INFO - 'sps_profile_interval': 0, 01:40:12 INFO - 'suite': 'dromaeojs', 01:40:12 INFO - 'system_bits': '32', 01:40:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:40:12 INFO - 'title': 't-yosemite-r7-0285', 01:40:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:40:12 INFO - 'use_talos_json': True, 01:40:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:40:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:40:12 INFO - 'work_dir': 'build'} 01:40:12 INFO - ##### 01:40:12 INFO - ##### Running clobber step. 01:40:12 INFO - ##### 01:40:12 INFO - Running pre-action listener: _resource_record_pre_action 01:40:12 INFO - Running main action method: clobber 01:40:12 INFO - rmtree: /builds/slave/test/build 01:40:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:40:14 INFO - Running post-action listener: _resource_record_post_action 01:40:14 INFO - ##### 01:40:14 INFO - ##### Finished clobber step (success) 01:40:14 INFO - ##### 01:40:14 INFO - ##### 01:40:14 INFO - ##### Running read-buildbot-config step. 01:40:14 INFO - ##### 01:40:14 INFO - Running pre-action listener: _resource_record_pre_action 01:40:14 INFO - Running main action method: read_buildbot_config 01:40:14 INFO - Using buildbot properties: 01:40:14 INFO - { 01:40:14 INFO - "project": "", 01:40:14 INFO - "product": "firefox", 01:40:14 INFO - "script_repo_revision": "production", 01:40:14 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 01:40:14 INFO - "repository": "", 01:40:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 01:40:14 INFO - "buildid": "20160528004338", 01:40:14 INFO - "pgo_build": "False", 01:40:14 INFO - "basedir": "/builds/slave/test", 01:40:14 INFO - "buildnumber": 25, 01:40:14 INFO - "slavename": "t-yosemite-r7-0285", 01:40:14 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 01:40:14 INFO - "platform": "yosemite_r7", 01:40:14 INFO - "scheduler": "tests-fx-team-macosx64-talos", 01:40:14 INFO - "branch": "fx-team", 01:40:14 INFO - "repo_path": "integration/fx-team", 01:40:14 INFO - "revision": "2e2c3435f648e36080c49c46416b710e0b52d55a", 01:40:14 INFO - "stage_platform": "macosx64", 01:40:14 INFO - "builduid": "51916248861840cc96bd23b7b83fbcb9", 01:40:14 INFO - "slavebuilddir": "test" 01:40:14 INFO - } 01:40:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 01:40:14 INFO - Running post-action listener: _resource_record_post_action 01:40:14 INFO - ##### 01:40:14 INFO - ##### Finished read-buildbot-config step (success) 01:40:14 INFO - ##### 01:40:14 INFO - ##### 01:40:14 INFO - ##### Running download-and-extract step. 01:40:14 INFO - ##### 01:40:14 INFO - Running pre-action listener: _resource_record_pre_action 01:40:14 INFO - Running main action method: download_and_extract 01:40:14 INFO - mkdir: /builds/slave/test/build/tests 01:40:14 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')]} 01:40:14 INFO - https://queue.taskcluster.net/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:40:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:40:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:40:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/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 01:40:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/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 01:40:31 INFO - Downloaded 1199 bytes. 01:40:31 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 01:40:31 INFO - Using the following test package requirements: 01:40:31 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:40:31 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:40:31 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 01:40:31 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:40:31 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 01:40:31 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:40:31 INFO - u'jsshell-mac.zip'], 01:40:31 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:40:31 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 01:40:31 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:40:31 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:40:31 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 01:40:31 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:40:31 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 01:40:31 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:40:31 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 01:40:31 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:40:31 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 01:40:31 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:40:31 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')]} 01:40:31 INFO - https://queue.taskcluster.net/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:40:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:40:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:40:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/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 01:40:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/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 01:40:36 INFO - Downloaded 18684712 bytes. 01:40: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'] 01:40: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 01:40:39 INFO - Return code: 0 01:40: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 01:40: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')]} 01:40:39 INFO - https://queue.taskcluster.net/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:40:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:40:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:40:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/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 01:40:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/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 01:40:41 INFO - Downloaded 18684712 bytes. 01:40:41 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'] 01:40:41 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 01:40:45 INFO - Return code: 0 01:40: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')]} 01:40:45 INFO - https://queue.taskcluster.net/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:40:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:40:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:40:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/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 01:40:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/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 01:40:46 INFO - Downloaded 10834034 bytes. 01:40:46 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'] 01:40:46 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 01:40:46 INFO - Return code: 0 01:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:46 INFO - https://queue.taskcluster.net/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 01:40:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:40:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:40:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 01:40:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N7oEDUU0SaaL1RbXSVBkmA/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 01:40:50 INFO - Downloaded 110449098 bytes. 01:40:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:40:50 INFO - mkdir: /builds/slave/test/properties 01:40:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:40:50 INFO - Writing to file /builds/slave/test/properties/build_url 01:40:50 INFO - Contents: 01:40:50 INFO - build_url:https://queue.taskcluster.net/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:40:51 INFO - Running post-action listener: _resource_record_post_action 01:40:51 INFO - Running post-action listener: set_extra_try_arguments 01:40:51 INFO - ##### 01:40:51 INFO - ##### Finished download-and-extract step (success) 01:40:51 INFO - ##### 01:40:51 INFO - ##### 01:40:51 INFO - ##### Running populate-webroot step. 01:40:51 INFO - ##### 01:40:51 INFO - Running pre-action listener: _resource_record_pre_action 01:40:51 INFO - Running main action method: populate_webroot 01:40:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:40:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:40:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:40:51 INFO - '--tppagecycles', 01:40:51 INFO - '7'], 01:40:51 INFO - 'tests': ['tsvgm']}}, 01:40:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:40:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:40:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:40:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:40:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:40:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:40:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:51 INFO - 'talos_options': ['--disable-e10s'], 01:40:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:40:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:40:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:51 INFO - 'talos_options': ['--disable-e10s'], 01:40:51 INFO - 'tests': ['damp', 'tps']}, 01:40:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:51 INFO - 'tests': ['damp', 'tps']}, 01:40:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:40:51 INFO - 'tests': ['dromaeo_dom']}, 01:40:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:40:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:40:51 INFO - 'tests': ['a11yr', 01:40:51 INFO - 'ts_paint', 01:40:51 INFO - 'tpaint', 01:40:51 INFO - 'sessionrestore', 01:40:51 INFO - 'sessionrestore_no_auto_restore', 01:40:51 INFO - 'tabpaint']}, 01:40:51 INFO - 'other-e10s': {'tests': ['a11yr', 01:40:51 INFO - 'ts_paint', 01:40:51 INFO - 'tpaint', 01:40:51 INFO - 'sessionrestore', 01:40:51 INFO - 'sessionrestore_no_auto_restore', 01:40:51 INFO - 'tabpaint']}, 01:40:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:40:51 INFO - 'ts_paint', 01:40:51 INFO - 'tpaint', 01:40:51 INFO - 'sessionrestore', 01:40:51 INFO - 'sessionrestore_no_auto_restore', 01:40:51 INFO - 'tabpaint']}, 01:40:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:40:51 INFO - 'ts_paint', 01:40:51 INFO - 'tpaint', 01:40:51 INFO - 'sessionrestore', 01:40:51 INFO - 'sessionrestore_no_auto_restore', 01:40:51 INFO - 'tabpaint']}, 01:40:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:40:51 INFO - 'tests': ['a11yr', 01:40:51 INFO - 'ts_paint', 01:40:51 INFO - 'tpaint', 01:40:51 INFO - 'sessionrestore', 01:40:51 INFO - 'sessionrestore_no_auto_restore', 01:40:51 INFO - 'tabpaint']}, 01:40:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:40:51 INFO - 'tests': ['a11yr', 01:40:51 INFO - 'ts_paint', 01:40:51 INFO - 'tpaint', 01:40:51 INFO - 'sessionrestore', 01:40:51 INFO - 'sessionrestore_no_auto_restore', 01:40:51 INFO - 'tabpaint']}, 01:40:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:40:51 INFO - 'tests': ['tsvgx', 01:40:51 INFO - 'tsvgr_opacity', 01:40:51 INFO - 'tart', 01:40:51 INFO - 'tscrollx', 01:40:51 INFO - 'cart']}, 01:40:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:40:51 INFO - 'tsvgr_opacity', 01:40:51 INFO - 'tart', 01:40:51 INFO - 'tscrollx', 01:40:51 INFO - 'cart']}, 01:40:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:51 INFO - 'talos_options': ['--disable-e10s'], 01:40:51 INFO - 'tests': ['tp5o']}, 01:40:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:51 INFO - 'tests': ['tp5o']}, 01:40:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:51 INFO - 'talos_options': ['--disable-e10s', 01:40:51 INFO - '--xperf_path', 01:40:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:40:51 INFO - 'tests': ['tp5n']}, 01:40:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:51 INFO - 'talos_options': ['--xperf_path', 01:40:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:40:51 INFO - 'tests': ['tp5n']}}, 01:40:51 INFO - 'talos.zip': {'path': '', 01:40:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:40:51 INFO - Running post-action listener: _resource_record_post_action 01:40:51 INFO - ##### 01:40:51 INFO - ##### Finished populate-webroot step (success) 01:40:51 INFO - ##### 01:40:51 INFO - ##### 01:40:51 INFO - ##### Running create-virtualenv step. 01:40:51 INFO - ##### 01:40:51 INFO - Running pre-action listener: _resource_record_pre_action 01:40:51 INFO - Running main action method: create_virtualenv 01:40:51 INFO - Creating virtualenv /builds/slave/test/build/venv 01:40:51 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 01:40:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:40:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:40:51 INFO - Using real prefix '/tools/python27' 01:40:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:40:52 INFO - Installing distribute.............................................................................................................................................................................................done. 01:40:55 INFO - Installing pip.................done. 01:40:55 INFO - Return code: 0 01:40:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:40:55 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')]} 01:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:55 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')]} 01:40:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:55 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 0x1095d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c359cd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109477d30>, '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 0x10929bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10946eb20>, '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.JWJxr50QVH/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.O8dIQaUOfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 01:40:55 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 01:40:55 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 01:40:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JWJxr50QVH/Render', 01:40:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:40:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:40:55 INFO - 'HOME': '/Users/cltbld', 01:40:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:40:55 INFO - 'LOGNAME': 'cltbld', 01:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:55 INFO - 'MOZ_NO_REMOTE': '1', 01:40:55 INFO - 'NO_EM_RESTART': '1', 01:40:55 INFO - 'PAGER': '/bin/cat', 01:40:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:55 INFO - 'PWD': '/builds/slave/test', 01:40:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:40:55 INFO - 'SHELL': '/bin/bash', 01:40:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8dIQaUOfs/Listeners', 01:40:55 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 01:40:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:40:55 INFO - 'USER': 'cltbld', 01:40:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:40:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:55 INFO - 'XPC_FLAGS': '0x0', 01:40:55 INFO - 'XPC_SERVICE_NAME': '0', 01:40:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:40:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:55 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 01:40:55 INFO - Cleaning up... 01:40:55 INFO - Return code: 0 01:40:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:40:55 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')]} 01:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:55 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')]} 01:40:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:55 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 0x1095d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c359cd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109477d30>, '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 0x10929bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10946eb20>, '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.JWJxr50QVH/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.O8dIQaUOfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 01:40:55 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 01:40:55 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 01:40:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JWJxr50QVH/Render', 01:40:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:40:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:40:55 INFO - 'HOME': '/Users/cltbld', 01:40:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:40:55 INFO - 'LOGNAME': 'cltbld', 01:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:55 INFO - 'MOZ_NO_REMOTE': '1', 01:40:55 INFO - 'NO_EM_RESTART': '1', 01:40:55 INFO - 'PAGER': '/bin/cat', 01:40:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:55 INFO - 'PWD': '/builds/slave/test', 01:40:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:40:55 INFO - 'SHELL': '/bin/bash', 01:40:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8dIQaUOfs/Listeners', 01:40:55 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 01:40:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:40:55 INFO - 'USER': 'cltbld', 01:40:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:40:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:55 INFO - 'XPC_FLAGS': '0x0', 01:40:55 INFO - 'XPC_SERVICE_NAME': '0', 01:40:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:40:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:55 INFO - Downloading/unpacking psutil>=3.1.1 01:40:55 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 01:40:55 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 01:40:55 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 01:40:55 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 01:40:55 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 01:40:55 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 01:40:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:40:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:40:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:40:58 INFO - Installing collected packages: psutil 01:40:58 INFO - Running setup.py install for psutil 01:40:58 INFO - building 'psutil._psutil_osx' extension 01:40: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_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 01:40:59 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 01:40:59 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 01:40:59 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 01:40:59 INFO - building 'psutil._psutil_posix' extension 01:40:59 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 01:40:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:40:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:40:59 INFO - ^ 01:40:59 INFO - 1 warning generated. 01:40:59 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 01:40:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:40:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:40:59 INFO - Successfully installed psutil 01:40:59 INFO - Cleaning up... 01:41:00 INFO - Return code: 0 01:41:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:41:00 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')]} 01:41:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:00 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')]} 01:41:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:00 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 0x1095d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c359cd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109477d30>, '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 0x10929bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10946eb20>, '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.JWJxr50QVH/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.O8dIQaUOfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 01:41:00 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 01:41:00 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 01:41:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JWJxr50QVH/Render', 01:41:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:00 INFO - 'HOME': '/Users/cltbld', 01:41:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:00 INFO - 'LOGNAME': 'cltbld', 01:41:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:00 INFO - 'MOZ_NO_REMOTE': '1', 01:41:00 INFO - 'NO_EM_RESTART': '1', 01:41:00 INFO - 'PAGER': '/bin/cat', 01:41:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:00 INFO - 'PWD': '/builds/slave/test', 01:41:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:00 INFO - 'SHELL': '/bin/bash', 01:41:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8dIQaUOfs/Listeners', 01:41:00 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 01:41:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:00 INFO - 'USER': 'cltbld', 01:41:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:00 INFO - 'XPC_FLAGS': '0x0', 01:41:00 INFO - 'XPC_SERVICE_NAME': '0', 01:41:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:41:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:41:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:41:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:41:02 INFO - Installing collected packages: mozsystemmonitor 01:41:02 INFO - Running setup.py install for mozsystemmonitor 01:41:02 INFO - Successfully installed mozsystemmonitor 01:41:02 INFO - Cleaning up... 01:41:02 INFO - Return code: 0 01:41:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:41:02 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')]} 01:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:02 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')]} 01:41:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:02 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 0x1095d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c359cd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109477d30>, '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 0x10929bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10946eb20>, '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.JWJxr50QVH/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.O8dIQaUOfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 01:41:02 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 01:41:02 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 01:41:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JWJxr50QVH/Render', 01:41:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:02 INFO - 'HOME': '/Users/cltbld', 01:41:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:02 INFO - 'LOGNAME': 'cltbld', 01:41:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:02 INFO - 'MOZ_NO_REMOTE': '1', 01:41:02 INFO - 'NO_EM_RESTART': '1', 01:41:02 INFO - 'PAGER': '/bin/cat', 01:41:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:02 INFO - 'PWD': '/builds/slave/test', 01:41:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:02 INFO - 'SHELL': '/bin/bash', 01:41:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8dIQaUOfs/Listeners', 01:41:02 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 01:41:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:02 INFO - 'USER': 'cltbld', 01:41:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:02 INFO - 'XPC_FLAGS': '0x0', 01:41:02 INFO - 'XPC_SERVICE_NAME': '0', 01:41:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:03 INFO - Downloading/unpacking blobuploader==1.2.4 01:41:03 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 01:41:03 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 01:41:03 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 01:41:03 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 01:41:03 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 01:41:03 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 01:41:05 INFO - Downloading blobuploader-1.2.4.tar.gz 01:41:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:41:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:41:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:41:06 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 01:41:06 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 01:41:06 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 01:41:06 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 01:41:06 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 01:41:06 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 01:41:06 INFO - Downloading docopt-0.6.1.tar.gz 01:41:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:41:06 INFO - Installing collected packages: blobuploader, requests, docopt 01:41:06 INFO - Running setup.py install for blobuploader 01:41:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:41:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:41:07 INFO - Running setup.py install for requests 01:41:07 INFO - Running setup.py install for docopt 01:41:07 INFO - Successfully installed blobuploader requests docopt 01:41:07 INFO - Cleaning up... 01:41:07 INFO - Return code: 0 01:41:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:41:07 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')]} 01:41:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:07 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')]} 01:41:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:07 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 0x1095d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c359cd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109477d30>, '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 0x10929bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10946eb20>, '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.JWJxr50QVH/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.O8dIQaUOfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 01:41:07 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 01:41:07 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 01:41:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JWJxr50QVH/Render', 01:41:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:07 INFO - 'HOME': '/Users/cltbld', 01:41:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:07 INFO - 'LOGNAME': 'cltbld', 01:41:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:07 INFO - 'MOZ_NO_REMOTE': '1', 01:41:07 INFO - 'NO_EM_RESTART': '1', 01:41:07 INFO - 'PAGER': '/bin/cat', 01:41:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:07 INFO - 'PWD': '/builds/slave/test', 01:41:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:07 INFO - 'SHELL': '/bin/bash', 01:41:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8dIQaUOfs/Listeners', 01:41:07 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 01:41:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:07 INFO - 'USER': 'cltbld', 01:41:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:07 INFO - 'XPC_FLAGS': '0x0', 01:41:07 INFO - 'XPC_SERVICE_NAME': '0', 01:41:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:41:07 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-bZVduM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:41:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-a70XyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:41:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-Xvjdmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:41:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-mx_M2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:41:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-1uEvD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:41:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-Ro6_da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:41:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-mRvuUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:41:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-TgmZcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:41:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-5JYhGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:41:08 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-F0GhLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:41:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:41:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-sN0kGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:41:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:41:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-jIbHug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:41:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:41:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-E9JObO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:41:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:41:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-VQZqeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:41:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-KF61wi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:41:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-WUsRR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:41:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:41:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-4fqUsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:41:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:41:09 INFO - Running setup.py install for manifestparser 01:41:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:41:09 INFO - Running setup.py install for mozcrash 01:41:10 INFO - Running setup.py install for mozdebug 01:41:10 INFO - Running setup.py install for mozdevice 01:41:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:41:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:41:10 INFO - Running setup.py install for mozfile 01:41:10 INFO - Running setup.py install for mozhttpd 01:41:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:41:10 INFO - Running setup.py install for mozinfo 01:41:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:41:10 INFO - Running setup.py install for mozInstall 01:41:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:41:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:41:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:41:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:41:10 INFO - Running setup.py install for mozleak 01:41:11 INFO - Running setup.py install for mozlog 01:41:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:41:11 INFO - Running setup.py install for moznetwork 01:41:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:41:11 INFO - Running setup.py install for mozprocess 01:41:11 INFO - Running setup.py install for mozprofile 01:41:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:41:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:41:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:41:11 INFO - Running setup.py install for mozrunner 01:41:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:41:11 INFO - Running setup.py install for mozscreenshot 01:41:12 INFO - Running setup.py install for moztest 01:41:12 INFO - Running setup.py install for mozversion 01:41:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:41:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:41:12 INFO - Cleaning up... 01:41:12 INFO - Return code: 0 01:41:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:41:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:12 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 0x1095d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c359cd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109477d30>, '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 0x10929bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10946eb20>, '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.JWJxr50QVH/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.O8dIQaUOfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 01:41:12 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 01:41:12 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 01:41:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JWJxr50QVH/Render', 01:41:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:12 INFO - 'HOME': '/Users/cltbld', 01:41:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:12 INFO - 'LOGNAME': 'cltbld', 01:41:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:12 INFO - 'MOZ_NO_REMOTE': '1', 01:41:12 INFO - 'NO_EM_RESTART': '1', 01:41:12 INFO - 'PAGER': '/bin/cat', 01:41:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:12 INFO - 'PWD': '/builds/slave/test', 01:41:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:12 INFO - 'SHELL': '/bin/bash', 01:41:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8dIQaUOfs/Listeners', 01:41:12 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 01:41:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:12 INFO - 'USER': 'cltbld', 01:41:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:12 INFO - 'XPC_FLAGS': '0x0', 01:41:12 INFO - 'XPC_SERVICE_NAME': '0', 01:41:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:41:12 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-RljRmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:41:12 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)) 01:41:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:41:12 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-zlHGIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:41:12 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)) 01:41:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:41:12 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-mX_6Vb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:41:12 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)) 01:41:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:41:12 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-oPZQu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:41:13 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)) 01:41:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:41:13 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-k9QW1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:41:13 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)) 01:41:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:41:13 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-g3wu11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:41:13 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)) 01:41:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:41:13 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-nwsc4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:41:13 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)) 01:41:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:41:13 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-NnUD4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:41:13 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)) 01:41:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:41:13 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-1__n_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:41:13 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)) 01:41:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:41:13 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-hK8FbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:41:13 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)) 01:41:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:41:13 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-YxfvgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:41:13 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)) 01:41:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:41:13 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-TBGQZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:41:13 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)) 01:41:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:41:13 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-upDqu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:41:14 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)) 01:41:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:41:14 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-_6bBfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:41:14 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)) 01:41:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:14 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-dJ9DnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:14 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)) 01:41:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:41:14 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-oaIYob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:41:14 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)) 01:41:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:41:14 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-t8dHt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:41:14 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)) 01:41:14 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)) 01:41:14 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)) 01:41:14 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)) 01:41:14 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)) 01:41:14 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)) 01:41:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:16 INFO - Downloading blessings-1.6.tar.gz 01:41:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:41:17 INFO - Installing collected packages: blessings 01:41:17 INFO - Running setup.py install for blessings 01:41:17 INFO - Successfully installed blessings 01:41:17 INFO - Cleaning up... 01:41:17 INFO - Return code: 0 01:41:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:41:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:41:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:41:17 INFO - Reading from file tmpfile_stdout 01:41:17 INFO - Current package versions: 01:41:17 INFO - blessings == 1.6 01:41:17 INFO - blobuploader == 1.2.4 01:41:17 INFO - docopt == 0.6.1 01:41:17 INFO - manifestparser == 1.1 01:41:17 INFO - mozInstall == 1.12 01:41:17 INFO - mozcrash == 0.17 01:41:17 INFO - mozdebug == 0.1 01:41:17 INFO - mozdevice == 0.48 01:41:17 INFO - mozfile == 1.2 01:41:17 INFO - mozhttpd == 0.7 01:41:17 INFO - mozinfo == 0.9 01:41:17 INFO - mozleak == 0.1 01:41:17 INFO - mozlog == 3.2 01:41:17 INFO - moznetwork == 0.27 01:41:17 INFO - mozprocess == 0.22 01:41:17 INFO - mozprofile == 0.28 01:41:17 INFO - mozrunner == 6.11 01:41:17 INFO - mozscreenshot == 0.1 01:41:17 INFO - mozsystemmonitor == 0.3 01:41:17 INFO - moztest == 0.7 01:41:17 INFO - mozversion == 1.4 01:41:17 INFO - psutil == 3.1.1 01:41:17 INFO - requests == 1.2.3 01:41:17 INFO - wsgiref == 0.1.2 01:41:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:41:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:17 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 0x1095d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c359cd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109477d30>, '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 0x10929bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10946eb20>, '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.JWJxr50QVH/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.O8dIQaUOfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 01:41:17 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 01:41:17 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 01:41:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JWJxr50QVH/Render', 01:41:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:17 INFO - 'HOME': '/Users/cltbld', 01:41:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:17 INFO - 'LOGNAME': 'cltbld', 01:41:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:17 INFO - 'MOZ_NO_REMOTE': '1', 01:41:17 INFO - 'NO_EM_RESTART': '1', 01:41:17 INFO - 'PAGER': '/bin/cat', 01:41:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:17 INFO - 'PWD': '/builds/slave/test', 01:41:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:17 INFO - 'SHELL': '/bin/bash', 01:41:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8dIQaUOfs/Listeners', 01:41:17 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 01:41:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:17 INFO - 'USER': 'cltbld', 01:41:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:17 INFO - 'XPC_FLAGS': '0x0', 01:41:17 INFO - 'XPC_SERVICE_NAME': '0', 01:41:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:18 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)) 01:41:18 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)) 01:41:18 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)) 01:41:18 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)) 01:41:18 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)) 01:41:18 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)) 01:41:18 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)) 01:41:18 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)) 01:41:18 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)) 01:41:18 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)) 01:41:18 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)) 01:41:18 INFO - Cleaning up... 01:41:18 INFO - Return code: 0 01:41:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:41:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1095d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c359cd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109477d30>, '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 0x10929bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10946eb20>, '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.JWJxr50QVH/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.O8dIQaUOfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 01:41:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:41:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:41:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JWJxr50QVH/Render', 01:41:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:18 INFO - 'HOME': '/Users/cltbld', 01:41:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:18 INFO - 'LOGNAME': 'cltbld', 01:41:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:18 INFO - 'MOZ_NO_REMOTE': '1', 01:41:18 INFO - 'NO_EM_RESTART': '1', 01:41:18 INFO - 'PAGER': '/bin/cat', 01:41:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:18 INFO - 'PWD': '/builds/slave/test', 01:41:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:18 INFO - 'SHELL': '/bin/bash', 01:41:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8dIQaUOfs/Listeners', 01:41:18 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 01:41:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:18 INFO - 'USER': 'cltbld', 01:41:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:18 INFO - 'XPC_FLAGS': '0x0', 01:41:18 INFO - 'XPC_SERVICE_NAME': '0', 01:41:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:18 INFO - Downloading/unpacking jsonschema 01:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:41:20 INFO - Downloading/unpacking functools32 (from jsonschema) 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41:21 INFO - Downloading functools32-3.2.3-2.tar.gz 01:41:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:41:21 INFO - warning: no files found matching '*.txt' 01:41:21 INFO - no previously-included directories found matching 'build' 01:41:21 INFO - no previously-included directories found matching 'dist' 01:41:21 INFO - no previously-included directories found matching '.git*' 01:41:21 INFO - Installing collected packages: jsonschema, functools32 01:41:21 INFO - Running setup.py install for functools32 01:41:21 INFO - warning: no files found matching '*.txt' 01:41:21 INFO - no previously-included directories found matching 'build' 01:41:21 INFO - no previously-included directories found matching 'dist' 01:41:21 INFO - no previously-included directories found matching '.git*' 01:41:21 INFO - Successfully installed jsonschema functools32 01:41:21 INFO - Cleaning up... 01:41:21 INFO - Return code: 0 01:41:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:41:21 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')]} 01:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:21 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')]} 01:41:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:21 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 0x1095d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c359cd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109477d30>, '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 0x10929bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10946eb20>, '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.JWJxr50QVH/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.O8dIQaUOfs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 01:41:21 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 01:41:21 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 01:41:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JWJxr50QVH/Render', 01:41:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:21 INFO - 'HOME': '/Users/cltbld', 01:41:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:21 INFO - 'LOGNAME': 'cltbld', 01:41:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:21 INFO - 'MOZ_NO_REMOTE': '1', 01:41:21 INFO - 'NO_EM_RESTART': '1', 01:41:21 INFO - 'PAGER': '/bin/cat', 01:41:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:21 INFO - 'PWD': '/builds/slave/test', 01:41:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:21 INFO - 'SHELL': '/bin/bash', 01:41:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8dIQaUOfs/Listeners', 01:41:21 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 01:41:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:21 INFO - 'USER': 'cltbld', 01:41:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:21 INFO - 'XPC_FLAGS': '0x0', 01:41:21 INFO - 'XPC_SERVICE_NAME': '0', 01:41:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:22 INFO - Downloading/unpacking flake8 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:41:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:41:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:41:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:41:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:41:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:41:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:41:25 INFO - no previously-included directories found matching 'docs/_build' 01:41:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:41:25 INFO - Running setup.py install for pep8 01:41:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:41:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:41:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:41:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:41:26 INFO - no previously-included directories found matching 'docs/_build' 01:41:26 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:41:26 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:41:26 INFO - Cleaning up... 01:41:26 INFO - Return code: 0 01:41:26 INFO - Running post-action listener: _resource_record_post_action 01:41:26 INFO - Running post-action listener: _start_resource_monitoring 01:41:26 INFO - Starting resource monitoring. 01:41:26 INFO - ##### 01:41:26 INFO - ##### Finished create-virtualenv step (success) 01:41:26 INFO - ##### 01:41:26 INFO - ##### 01:41:26 INFO - ##### Running install step. 01:41:26 INFO - ##### 01:41:26 INFO - Running pre-action listener: _resource_record_pre_action 01:41:26 INFO - Running main action method: install 01:41:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:41:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:41:26 INFO - Reading from file tmpfile_stdout 01:41:26 INFO - Detecting whether we're running mozinstall >=1.0... 01:41:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:41:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:41:26 INFO - Reading from file tmpfile_stdout 01:41:26 INFO - Output received: 01:41:26 INFO - Usage: mozinstall [options] installer 01:41:26 INFO - Options: 01:41:26 INFO - -h, --help show this help message and exit 01:41:26 INFO - -d DEST, --destination=DEST 01:41:26 INFO - Directory to install application into. [default: 01:41:26 INFO - "/builds/slave/test"] 01:41:26 INFO - --app=APP Application being installed. [default: firefox] 01:41:26 INFO - mkdir: /builds/slave/test/build/application 01:41:26 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'] 01:41:26 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 01:42:00 INFO - Reading from file tmpfile_stdout 01:42:00 INFO - Output received: 01:42:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:42:00 INFO - Running post-action listener: _resource_record_post_action 01:42:00 INFO - ##### 01:42:00 INFO - ##### Finished install step (success) 01:42:00 INFO - ##### 01:42:00 INFO - ##### 01:42:00 INFO - ##### Running run-tests step. 01:42:00 INFO - ##### 01:42:00 INFO - Running pre-action listener: _resource_record_pre_action 01:42:00 INFO - Running main action method: run_tests 01:42:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:42:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:42:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:42:00 INFO - 2016-05-28 01:42:00.521 screenresolution[1652:8384] starting screenresolution argv=screenresolution get 01:42:00 INFO - 2016-05-28 01:42:00.524 screenresolution[1652:8384] Display 0: 1600x1200x32@60 01:42:00 INFO - 2016-05-28 01:42:00.530 screenresolution[1653:8386] starting screenresolution argv=screenresolution list 01:42:00 INFO - Available Modes on Display 0 01:42:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:42:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:42:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:42:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:42:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:42:00 INFO - Intel Iris: 01:42:00 INFO - Chipset Model: Intel Iris 01:42:00 INFO - Type: GPU 01:42:00 INFO - Bus: Built-In 01:42:00 INFO - VRAM (Dynamic, Max): 1536 MB 01:42:00 INFO - Vendor: Intel (0x8086) 01:42:00 INFO - Device ID: 0x0a2e 01:42:00 INFO - Revision ID: 0x0009 01:42:00 INFO - Displays: 01:42:00 INFO - Display: 01:42:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:42:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:42:00 INFO - Display Serial Number: 1600x1200 60 01:42:00 INFO - Main Display: Yes 01:42:00 INFO - Mirror: Off 01:42:00 INFO - Online: Yes 01:42:00 INFO - Rotation: Supported 01:42:00 INFO - Return code: 0 01:42:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:42:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:42:00 INFO - Python 2.7.3 01:42:00 INFO - Return code: 0 01:42:00 INFO - grabbing minidump binary from tooltool 01:42:00 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')]} 01:42:00 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 0x109477d30>, '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 0x10929bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10946eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:42:00 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 01:42:00 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 01:42:00 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 01:42:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:42:00 INFO - Return code: 0 01:42:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:42:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:42:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:42:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:42:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:42:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:42:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:42:01 INFO - Return code: 0 01:42:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0285', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:42:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0285 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:42:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JWJxr50QVH/Render', 01:42:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:01 INFO - 'HOME': '/Users/cltbld', 01:42:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:01 INFO - 'LOGNAME': 'cltbld', 01:42:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:42:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:01 INFO - 'MOZ_NO_REMOTE': '1', 01:42:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:42:01 INFO - 'NO_EM_RESTART': '1', 01:42:01 INFO - 'PAGER': '/bin/cat', 01:42:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:01 INFO - 'PWD': '/builds/slave/test', 01:42:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:42:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:01 INFO - 'SHELL': '/bin/bash', 01:42:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8dIQaUOfs/Listeners', 01:42:01 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 01:42:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:01 INFO - 'USER': 'cltbld', 01:42:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:01 INFO - 'XPC_FLAGS': '0x0', 01:42:01 INFO - 'XPC_SERVICE_NAME': '0', 01:42:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0285', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:42:02 INFO - mozversion application_buildid: 20160528004338 01:42:02 INFO - mozversion application_changeset: 2e2c3435f648e36080c49c46416b710e0b52d55a 01:42:02 INFO - mozversion application_display_name: Nightly 01:42:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:42:02 INFO - mozversion application_name: Firefox 01:42:02 INFO - mozversion application_remotingname: firefox 01:42:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:42:02 INFO - mozversion application_vendor: Mozilla 01:42:02 INFO - mozversion application_version: 49.0a1 01:42:02 INFO - mozversion platform_buildid: 20160528004338 01:42:02 INFO - mozversion platform_changeset: 2e2c3435f648e36080c49c46416b710e0b52d55a 01:42:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:42:02 INFO - mozversion platform_version: 49.0a1 01:42:02 INFO - using testdate: 1464424922 01:42:02 INFO - actual date: 1464424922 01:42:02 INFO - starting webserver on 'localhost:49278' 01:42:02 INFO - SUITE-START | Running 2 tests 01:42:02 INFO - TEST-START | dromaeo_css 01:42:02 INFO - operating with platform_type : mac_ 01:42:02 INFO - Initialising browser for dromaeo_css test... 01:42:02 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmptaEHM0/profile http://localhost:49278/getInfo.html) 01:42:04 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 01:42:04 INFO - PROCESS | 1671 | colorDepth:24 01:42:04 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 01:42:04 INFO - PROCESS | 1671 | __metrics 01:42:04 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:42:04 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:42:05 INFO - TEST-INFO | 1671: exit 0 01:42:05 INFO - Browser initialized. 01:42:05 INFO - Running cycle 1/1 for dromaeo_css test... 01:42:05 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmptaEHM0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:42:17 INFO - PROCESS | 1674 | RSS: Main: 241524736 01:42:17 INFO - PROCESS | 1674 | 01:43:08 INFO - PROCESS | 1674 | 1464424988859 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] 01:45:19 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 01:45:20 INFO - PROCESS | 1674 | RSS: Main: 346591232 01:45:20 INFO - PROCESS | 1674 | 01:48:23 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 01:48:24 INFO - PROCESS | 1674 | RSS: Main: 378028032 01:48:24 INFO - PROCESS | 1674 | 01:51:26 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 01:51:26 INFO - PROCESS | 1674 | RSS: Main: 375013376 01:51:26 INFO - PROCESS | 1674 | 01:54:29 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 01:54:29 INFO - PROCESS | 1674 | RSS: Main: 372670464 01:54:29 INFO - PROCESS | 1674 | 01:57:33 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 01:57:33 INFO - PROCESS | 1674 | RSS: Main: 338456576 01:57:33 INFO - PROCESS | 1674 | 02:00:36 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 02:00:37 INFO - PROCESS | 1674 | RSS: Main: 391880704 02:00:37 INFO - PROCESS | 1674 | 02:00:37 INFO - PROCESS | 1674 | __start_tp_report 02:00:37 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 02:00:37 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 02:00:37 INFO - PROCESS | 1674 | |i|pagename|runs| 02:00:37 INFO - PROCESS | 1674 | |0;dojo.html;3394.1514621344663;3457.844397002133;3462.7229821614264;3486.163320803008;3509.719222462203;5552.028395030874;5595.944040559593;5653.349864765543;5680.517156041737;5710.5717071219615;5580.888382232353;5610.579206559504;5641.746121424536;5705.201271821938;5740.913886291709;5087.6184286178495;5116.718580478072;5173.637845350827;5178.300929374524;5186.351706036746;4475.440569928759;5258.2638430619745;5296.390915044762;5317.7607007684555;5347.919781203285;4257.701960862741;5102.362204724391;5104.540591346797;5158.613104017214;5167.3282473278605;4516.796744146488;4949.282354058641;5137.974310128426;5138.177891537336;5155.9484405155845;5125.026245013472;5141.691498510064;5157.638965272433;5201.219817027439;5223.817166399196;4853.417589889201;5302.337207849019;5594.916076258859;5650.208970744099;5650.886982260373;6140.846478838065;6160.938390616081;6163.7842675506145;6198.876022479571;6230.59501132425;6199.070139479069;6266.87466250677;6272.153259310026;6281.560290779647;6288.402601752826;5640.210370548127;5703.62926409782;5817.214676018725;5828.300603927513;5830.475257226827;6178.598391104564;6237.1891654084675;6248.218972628421;6288.371162883766;6299.968500157562;6014.6090504117965;6031.849203769941;6046.444712337435;6079.75680972765;6091.390860913962;6081.781570238115;6104.4200800923845;6118.284172697687;6158.4320739251025;6196.938030619636;6101.307919489667;6158.876822463526;6174.289956654952;6195.4424101831055;6200.596961197508;6197.597156184834;6203.534734894811;6215.875682486416;6228.252609686867;6228.719707613168;4827.348307978448;4881.536254055859;4898.510148985144;4900.142475066849;4936.6297527685565;4346.174226896879;4373.169097871394;4380.102079073782;4401.1417773534495;4447.332900064951;5609.15862620602;5613.382527921925;5630.493255606933;5658;5674.375818659944;6095.75616975316;6120.0205129226715;6171.259448866075;6201.410865967726;6203.16257305267;5601;5621.971890140523;5662.235598194274;5682.744276507485;5714.771409143587;5337.973310133502;5351.86620334487;5355.892882142335;5357.919631205535;5375.408705042442;6270.435660790551;6278.497720182374;6281.246250449975;6284.183056202664;6292.182016337847;6072.119542666377;6101.641069425165;6121.696863452088;6156.29202641702;6190.319064902767;6249.50003999688;6281.026440901667;6288.213973253343;6289.339619340087;6301.149344838388;6036.698165091816;6129.724162412613;6155.692215389123;6166.290876549075;6187.226596675416;5060.51925067118;5158.535731784157;6050.273967123973;6057.061155520725;6157.6613286269685;6027.849303767441;6040.395960403925;6045.274567051982;6046.3046749624355;6102.725377358119;6022.337542870193;6024.277086749618;6033.78881739137;6058.242719660043;6059.848503787441;5702.37273899879;5798.739056742541;5840.707964601838;5857.1799948008165;5962.9105563415715;6010.879782404415;6043.335233124265;6050.748533429907;6057.172954896699;6108.205933228652;5679.403662615366;5867.354590994902;5889.882202356014;5899.17411562373;5907.054871220584 02:00:37 INFO - PROCESS | 1674 | |1;ext.html;518.20455600653;519.1900635009388;526.3236740788088;540.4163503416311;585.721782153477;30683.07950761478;41852.790736046314;42649.57350426499;42715;44736.55263447364;44165.8958526037;44816.103677926425;44877.55122448775;44940.32589511152;44944.3258351125;8554.828903421927;9541.427514349127;9767;9768.925418203991;9781.951090244538;8622.223999840013;11547.769044619103;11708.180427370087;11725.296482211095;12021;10020.498975051254;11743.236689615147;11827.704307392341;11828.58599949002;11840.940795296012;8669;8808;8823.250023747989;11718.941405293004;11816.704582385422;8721;11372.886271137348;11676.883231167665;11706.941465292704;11947;40844;41102.79448602738;41228.38157427641;41271.587284127076;41332.38001429981;3495.860165593373;3624.873129440457;3631.527901372831;3796.943045854287;3841.673457756094;32093.839530802197;32256;32519.512207316908;32634.184145396317;33348.1662958428;2778.9583156252675;3268.96731032689;3272.345530893807;3279.7868138570916;3430.588329400488;481037;483690;486016;486049.99999999645;488070;62402.68798656068;62680.37319626837;62709.68645156745;62798.686006569675;63007.42481438001;66477.6676116626;66538.6692266152;66554;66643.66678166579;66717.33282667208;15381.84636152293;15784.447544335893;16597.91701041487;16844.410445634345;16862.494125176265;11773.88226117728;12838.614841554769;12982.026348023935;13042.739145217043;13073.673158171121;17574.736378954327;17581.187010078327;17625.942443453176;17633.707618009576;17634.294628214986;5077.593792496591;7092.716291348392;7154.105736782903;7187.7843664690145;7277.745278915212;856.9400141990001;888.2893685051932;928.865314529403;983.8720966274338;1064;29004.12987610375;30226;30344.54483182754;31027;31100.533491997638;1056.9365838049587;1061.856649352343;1066.722652110457;1146.5184622458587;1182.1193211057816;15839.683206335809;15894.966827156199;15934.681306373808;15942.28259728315;16357.836421635631;662014;667617;667718;668912;669778;49040;60278.69860650669;60540.69729651324;60692;60846.39153608407;1252.3925895940279;1286.7104901397297;1387.6322774464775;1409.1474657832068;1430.5207755402057;31175.90884319038;31604.36791264116;31768.523472147474;32086.83956580202;32489.187770305933;51447.742761285954;51701.74149129381;52262.73868630632;52315.47684523258;52331;443531;445337;445519;445634;447249;31802.978717022772;40477.595224047385;41723.37414938839;42506.36240456458;43911.56088439075;47706.28440573325;47954.280685790436;48535.271970919755;49399.50600493949;49579.50420495749;33515;40040.799796000836;40217;40562.594374057066;40990.79504602458;38689.03277418087;38820.80589597034;39257.803710981265;39776.2044759109;39998.40002399908;38302.61697383102;38773.612263877;38781.41827872528;38958.80520597379;39210.80394598123;33096.834515827264;33691;34700.30599388049;35594.822025889705;35781.46327805137;22500.774992250525;24396.6340504889;25650.39338810171;25792.484150317265;25988.480230395664 02:00:37 INFO - PROCESS | 1674 | |2;jquery.html;333.91318257253124;378.160483726128;378.88254641061275;393.5828022296364;401.43798681845414;16204.83795162048;19158.712619310703;21381.57236855264;21586.13655453784;21596.352109436702;20381.980900954968;20482.18071277147;21020.8948955255;21110.57778844422;21384.144634214652;5277.947220527803;5840.707964601774;6088.756449742027;6101.237345331833;6117.296510901258;5910.379410161936;6026.0960855871535;6058.363871793465;6086.908696369558;6106.511479081662;5788.305403351604;5929.436703513159;5990.101484777275;5991.281046274453;5995.910061349084;5834.183214349994;5964.821055368324;5978.491828194608;5984.461398474136;5994.280686317648;5076.36545431821;5161.509656582619;5187.740612969336;5189.870253243661;5191.740412979336;24646.753532464805;24818.5036299273;25091.62362564563;25371.238862834143;25429.618555721678;2165.231342873273;2248.111995761665;2347.2840783561005;2357.8821058946983;2508.4732206236713;14499.420023199165;15216.847831521654;15659.608509787233;15705.293261803246;15891.841081589151;1758.0594382005565;1789.937352192667;1809.6018875847292;1824.015031882795;1837.8345948864533;89554.10445895523;107536.92463075348;111155.44422277917;111552.44223778829;112773;9665.468399237969;10312.484375781181;10451.529681164366;10951.56193752241;11008.889911100787;12395.442205100791;13346.933265333611;13455.730885382238;13499.190048597116;13502.662433439242;8246;8482.491050536988;8525.19010693983;8583.39916205872;8610.354223433142;8536.615852286537;8537.701180458655;8565.48607083577;8689.087645797226;8699.695510657224;6370.362963703593;6474.287828338879;6511.837204069936;6547.738090476328;6655.633940133339;1781.8218178182078;1819.7543331650322;1823.5867202917846;1824.434425328126;1857.3870622694478;913.159892898535;920.9677661281823;996.7109538233976;1005.8139244239838;1052.8894466081103;17437.7384339235;21155.365339039854;21288.893555532122;21310;21315.573688526452;953.6948176583427;955.7323949294208;960.1023043454393;974.2595627323144;1003.8996100389902;11308.09535237179;11366.261193022427;11418.710857890886;11471.4264286787;11484.885151148548;516639;584676;596923;605199;606340;110546;111781;114947;117551;119020;979.4368238262884;1034.9637762678421;1050.495762034228;1052.2213561964245;1053.4100903494068;18448;18640.906795465937;18669.906650466663;18771.530711732317;19224.8077519223;39289.821305360885;53626.463735362144;53712;54756.178657319375;54796.90406191933;68877.65561172162;73511.63244183744;74153.25846741679;75067;75664;7843.156753746584;7974.368458408595;8043.034835819739;8323.625436855235;8350.749477515685;9009.504477253551;11837.112216583619;12697.55558555446;12832.486700531874;12928.224306541637;9790.020997900438;10271.640492582725;10895.400752958663;10994.010539051524;11098.667039988813;6082.695865206633;10727.40999245049;10989.725256868642;11001;11006.779864402506;10881.945590271998;10890.401027943539;11057.502412391623;11105.111591072533;11142.944285278521;7913.327367173854;7979.48133371329;7997.8400431992195;8219.671213151647;8237.258646721824;10036.548355324174;11105.94447027792;11211.383373914514;11233.71915702114;11258.549658013588 02:00:37 INFO - PROCESS | 1674 | |3;mootools.html;1397.7414178376994;1409.4362255097956;1595.6170519075422;1781.3230972230551;1955.7359756432877;7790.649420776064;8561.700340488083;8767.210951014407;8770.56147192641;8903.46579205248;8525.488470691764;8746.475211487315;8750.03749587545;8756.387052906299;8756.562171891395;2073.2536286936693;2143.024923659731;2144.7211862457857;2300.056976639578;2343.9648405273933;1991.273185287367;2147.9033443495;2173.978260217393;2363.9881800590974;2399.2922087983998;1974.7729011163756;2091.922659830189;2350.8001819845335;2351.953380745569;2356.552255071531;2126.5746850629935;2229.9108035678555;2283.851549649276;2345.6129738593113;2350.095213342867;1655.7747267022437;2081.469225347527;2294.644330128816;2371.0871314544024;2371.4664200554907;8651.567421628893;8732.476051436934;8741.868871966926;8743;8746.431481953652;5171.560417364529;5340.06548853949;5345.65253258537;5350.2777125088005;5364.731763411853;3497.702695270905;3632.8183590820354;3703.907402314937;3707.443883417509;3711;1044.937303761769;1086.8261078227445;1093.4095588382263;1098.9945050274778;1113.9777204455866;11709.180357374988;11765.647030589096;11791.764164716658;11799.646010619696;11804.055675545937;4571.108633816374;4700.694454860423;4775.450823155312;4788.186008378584;4794.256890182028;9941.502924853728;9986.20110391167;9994.400335979864;10003.949980250198;10005.79988400228;5278.049951008778;5336;5336.919946200733;5351.117065684196;5370.650907690988;3652.8538858445895;4361.978190109029;4808.158572249842;4885.2427873679635;4890.804367825318;3218.710316071518;3553.5202747629255;3732.272206919679;3843.3082045231567;3843.423486476995;1117.620009196877;1118.3829838178538;1119.7032786311634;1127.1771606727134;1158.368689064462;868.7306934850217;891.1266958380824;903.6430609909049;940.6895724410928;945.4164274839616;5701.543876489871;5822.417758224144;5950.256217972754;5956.672383018976;6083.209182806296;901.486152892858;928.3362395886974;950.4534892436876;986.8026394720941;987.5753426026733;4041.2119636671155;4141.130362623882;4230.196262710137;4231.788410579458;4247.893802654959;10568.048875601078;10714.946425267824;10812.189085818594;10814.78370432587;10818.783624327469;10426.165906727443;10663.73340666474;10779.784404312026;10780.407077610807;10795.244332896778;8001;8224.342052635895;8235.752927412188;8243.752687419386;8254.917450825415;5835.533157347487;5841.153032810304;6017.15759793638;6023.969880150747;6111.96944015277;8410.327173826201;9769.462679552693;9946.403215806786;9970.30207885441;9983.201343892326;9875;9926.305158638826;10214.897851021691;10249.12882405004;10282.691519254435;6977.79066628002;7437.925620743723;7452.701891924262;7549.0563679540055;7562.432817538816;7840.431913617185;8176.141505141874;8391.992960844738;8416.158384161536;8535.274501667427;7462.925370746223;7531.736389226351;7553.5845528494265;7686.192949740197;7708.8458230836195;7143.714251430092;7159.140903091662;7181;7295.598742069237;7364.079490063742;6980.965095174492;7056.470764692566;7066.187388450188;7067.2579379164445;7095.822604434724;4970.65205435616;4971.328870602567;5018.974905125451;5102.540771330598;5170.3278573785165;5077.669951453144;5139.023585518739;5170.043541944752;5183.144781111075;5281.498257665516 02:00:37 INFO - PROCESS | 1674 | |4;prototype.html;296.54332328214537;300.9638843338799;309.77386507849263;315.8436573895922;318.96172459304887;5091.618128640348;5168.914527949135;5330.546903513201;5343.141419340654;5426.155107263529;4769.642276829234;4948.921452989744;4969.577585905203;5168.379794424665;5225.8954820903555;3974.284628766821;4008.418779277003;4022.376531637586;4048.4736984192086;4053.3919912013284;3178.6821317868144;3927.56796752357;3997.4403583498333;4013.7190396672245;4045.59544045596;3409.437442821931;3710.9072273193115;3896.298666240076;3971.709194511781;4048.9797551012202;3517.4547945068557;3987;3995.5005624296964;3997.8600748973645;4058.5129784425762;3129.076922307902;3307.6361600223954;3550.1302180965827;3550.414181660023;3574.7140228781636;4476.664250181249;4770.282698228635;5108.846734597931;5166.225066240057;5182.507661772163;4057;4563.70335928167;4587.7017993830295;4589.518100599423;4630.282306242538;5117.795288188468;5267.841964741064;5431.321084864392;5451.727413629303;5473.31583552056;3447.465642825366;3475.6350583188655;3763.814398464502;3868.5357757069332;3869.2261547690573;5085.644004919694;5645.633033852787;5709.714514274269;5765.4811067004175;5769.942300577024;3396.352768907078;3418.6068602110577;3570.785752854785;3797.6771974381936;3840.4431357453013;3463.5497385339745;3482.6343233960574;3909.8045097745;3918.5885482024546;3926.411038344214;4271;4494.775261236925;4510.594046535827;4518.796654150571;4523.276275795923;5197.167543688699;5358.678479291255;5498.4981833265765;5542.584306177053;5556.805511807067;1150.2350936627217;1232.7795482472288;1239.9274627447194;1278;1292.8319318488536;751.898493703354;764.506893053977;854.3805740837843;892.8660700894918;903.6204793986542;4267.1892340455215;5149.716765577854;5150.587952963754;5164;5184.611154163377;5525.171224316385;5567.696433892934;5692.840473537227;5727.39862314451;5743.59794814359;4754.9791338680925;4799.3041009053495;5082.517160869711;5096.388433388017;5101.770420331094;2850.586664933573;2939.5149800283143;2969.88629264026;2993.985030074836;2996.3408050228913;4846.878828029327;4880.511948805091;5048.974755126201;5523.944760552424;5785.82642520725;4506.752128632957;5721.741216932268;5726.398728133569;5754.1944127822135;5778.88442231153;4938.98750756087;5746.310442746897;5767.106098554815;5795.94204057954;5812.331581868139;4671.976640116914;5760.366359700346;5779.335376431596;5784.566157538117;5800.8259752207505;4817.710937343771;5648.429964366285;5678.3186017678145;5689.914651280152;5704.828855134187;5724.740557077436;5950.3454619992635;6002.519798416203;6009.167204002822;6029.909551356821;3437.346904088315;3800.315943130263;3818;3824.5028146340796;3844.615538446133;4927.507249275044;5363.517283444508;5732.398098199629;5750;5755;4918.5981995131215;4981.62637802159;5293.676580854786;5647.745851436777;5677.971610141923;4455.665825063068;5547.8640723321005;5571.387147413862;5586.737371364604;5604.355499117653;4472.239719247671;4972.8508144755715;5063.987202559577;5481.451854814487;5532.253145825262;4930.778114984906;5390.757415916371;5551.500364967173;5598.580106491948;5612.214289999485;5095.515925987025;5146.073706732824;5179.98990196908;5278.736063196901;5299.231611416246 02:00:37 INFO - PROCESS | 1674 | |5;yui.html;676.4723515657786;827.3794654009491;835.6824406725443;860.466510763326;861.4960248254766;14702.04436711613;14716.852831471682;15177.089374637515;15250.38998440061;15333.310001049938;14323.355449004794;14373.92813035936;14383.496577619786;14436.711265774678;14454.855451445483;866.9826603467911;867.6052396159764;884.8230353929208;885.5660726244126;885.853834117373;707.3457052226695;733.8605664923674;870.9477431354139;900.9594568244412;904.6019751309435;950.1875896108832;952.8142012307603;955.498363359235;955.6941778630841;956.0105291023792;740.8481261341466;950.0309684122157;950.6577632052456;950.9952450237739;951.4243882451156;550.243415303957;550.9669419834783;552.215853488048;566.6600039976023;681.686424244848;14566.490172844005;15079.47221847241;15113.773293400607;15148.1668508232;15154.469593564172;13237.139585926883;13516.662083447894;13747.312634368242;14073.929630351886;14234.430622775077;12336.259824410565;12360.629181124581;12400.937995310056;12461.813072803916;12798.424070916832;4469.977650111761;4500.077484115748;4638.814447422099;4641.883952901171;4675.088357770236;67379;84959.72560411599;85887.14112858854;85933.57033214856;89587.55206223927;10666;10859.511321990529;11026.889731102747;11079.390633515073;11331.886681133248;21630.56738865214;21658.458538536663;21675.67486487704;21725.56548869014;21770.346889593337;6714.328567143247;7983.081945576333;8011.919880801234;8043.919560804434;8089.514629122272;4046.6560342371226;4283.892902677396;4392.121575684876;4399.076193999232;4585.266357382803;3414.2488652496413;3512.7716698415034;3516.2967406518796;3521.9119522012156;3537.327907697529;644.44899610833;654.4873099975641;655.6787174284566;669.0566301514841;679.0561120043144;560.2464684998752;647.3235468934975;658.9209294884645;671.2806885337;688.3460712323313;8438.282745966539;9103.089691030977;9258.629654813867;9910.256730745223;10063.597456101821;536.7074944155445;623.8614528485532;641.3810672700866;711.9786406407816;738.2506755643027;4315.525292217853;4339.500957389878;4440.866773996785;4487.483939346952;4499.235130027903;92409;93114.53442732742;94209.52895235617;95738.52130739302;95934.52032739791;59925.80148397008;79215.20784792193;82252.58873705713;84424;84705.72941405883;63890;65950.68098638;67445.42914411152;67450.32549674538;67488.6625566869;9508.667196648084;9544.284178686488;9574.760630984003;9613.182879455324;9619.470929098967;44546;44987.74577091798;46321;46907.76546117248;47570.28644570265;55887.44112558822;59644.70177649084;62479.6876015617;63092.68453657702;63267;11173.441327933733;12818.872807865682;13392.665183370495;13683.24742139192;13924.164550127025;14107.29463526799;14566.126032438087;14585.781213282022;14759.92620036893;14789.186594737394;13141.474341026253;13274.800877986645;13349.399277032751;13360.198388096745;13925.025248232527;13181.340932953506;13225.669358266121;13321;13586.93206533961;13906.51327203543;12806.61580152597;12881.549145780227;12914.22514649124;12943;13422.261775602436;10924.50839712199;11001;11224.438778061227;11397.601083962021;11462.14033947474;10724.410157441416;10865.619703310345;11316.547338106384;11339;11372.54509819598 02:00:37 INFO - PROCESS | 1674 | __end_tp_report 02:00:37 INFO - PROCESS | 1674 | __start_cc_report 02:00:37 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1148 02:00:37 INFO - PROCESS | 1674 | __end_cc_report 02:00:37 INFO - PROCESS | 1674 | __startTimestamp1464426037162__endTimestamp 02:00:37 INFO - PROCESS | 1674 | 02:00:37 INFO - PROCESS | 1674 | ------- Summary: start ------- 02:00:37 INFO - PROCESS | 1674 | Number of tests: 6 02:00:37 INFO - PROCESS | 1674 | 02:00:37 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5667.27 Median:5867.35 stddev:641.87 (10.9%) stddev-sans-first:618.57 02:00:37 INFO - PROCESS | 1674 | Values: 3394.2 3457.8 3462.7 3486.2 3509.7 5552.0 5595.9 5653.3 5680.5 5710.6 5580.9 5610.6 5641.7 5705.2 5740.9 5087.6 5116.7 5173.6 5178.3 5186.4 4475.4 5258.3 5296.4 5317.8 5347.9 4257.7 5102.4 5104.5 5158.6 5167.3 4516.8 4949.3 5138.0 5138.2 5155.9 5125.0 5141.7 5157.6 5201.2 5223.8 4853.4 5302.3 5594.9 5650.2 5650.9 6140.8 6160.9 6163.8 6198.9 6230.6 6199.1 6266.9 6272.2 6281.6 6288.4 5640.2 5703.6 5817.2 5828.3 5830.5 6178.6 6237.2 6248.2 6288.4 6300.0 6014.6 6031.8 6046.4 6079.8 6091.4 6081.8 6104.4 6118.3 6158.4 6196.9 6101.3 6158.9 6174.3 6195.4 6200.6 6197.6 6203.5 6215.9 6228.3 6228.7 4827.3 4881.5 4898.5 4900.1 4936.6 4346.2 4373.2 4380.1 4401.1 4447.3 5609.2 5613.4 5630.5 5658.0 5674.4 6095.8 6120.0 6171.3 6201.4 6203.2 5601.0 5622.0 5662.2 5682.7 5714.8 5338.0 5351.9 5355.9 5357.9 5375.4 6270.4 6278.5 6281.2 6284.2 6292.2 6072.1 6101.6 6121.7 6156.3 6190.3 6249.5 6281.0 6288.2 6289.3 6301.1 6036.7 6129.7 6155.7 6166.3 6187.2 5060.5 5158.5 6050.3 6057.1 6157.7 6027.8 6040.4 6045.3 6046.3 6102.7 6022.3 6024.3 6033.8 6058.2 6059.8 5702.4 5798.7 5840.7 5857.2 5962.9 6010.9 6043.3 6050.7 6057.2 6108.2 5679.4 5867.4 5889.9 5899.2 5907.1 02:00:37 INFO - PROCESS | 1674 | 02:00:37 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:68251.94 Median:39234.30 stddev:144353.04 (367.9%) stddev-sans-first:144668.41 02:00:37 INFO - PROCESS | 1674 | Values: 518.2 519.2 526.3 540.4 585.7 30683.1 41852.8 42649.6 42715.0 44736.6 44165.9 44816.1 44877.6 44940.3 44944.3 8554.8 9541.4 9767.0 9768.9 9782.0 8622.2 11547.8 11708.2 11725.3 12021.0 10020.5 11743.2 11827.7 11828.6 11840.9 8669.0 8808.0 8823.3 11718.9 11816.7 8721.0 11372.9 11676.9 11706.9 11947.0 40844.0 41102.8 41228.4 41271.6 41332.4 3495.9 3624.9 3631.5 3796.9 3841.7 32093.8 32256.0 32519.5 32634.2 33348.2 2779.0 3269.0 3272.3 3279.8 3430.6 481037.0 483690.0 486016.0 486050.0 488070.0 62402.7 62680.4 62709.7 62798.7 63007.4 66477.7 66538.7 66554.0 66643.7 66717.3 15381.8 15784.4 16597.9 16844.4 16862.5 11773.9 12838.6 12982.0 13042.7 13073.7 17574.7 17581.2 17625.9 17633.7 17634.3 5077.6 7092.7 7154.1 7187.8 7277.7 856.9 888.3 928.9 983.9 1064.0 29004.1 30226.0 30344.5 31027.0 31100.5 1056.9 1061.9 1066.7 1146.5 1182.1 15839.7 15895.0 15934.7 15942.3 16357.8 662014.0 667617.0 667718.0 668912.0 669778.0 49040.0 60278.7 60540.7 60692.0 60846.4 1252.4 1286.7 1387.6 1409.1 1430.5 31175.9 31604.4 31768.5 32086.8 32489.2 51447.7 51701.7 52262.7 52315.5 52331.0 443531.0 445337.0 445519.0 445634.0 447249.0 31803.0 40477.6 41723.4 42506.4 43911.6 47706.3 47954.3 48535.3 49399.5 49579.5 33515.0 40040.8 40217.0 40562.6 40990.8 38689.0 38820.8 39257.8 39776.2 39998.4 38302.6 38773.6 38781.4 38958.8 39210.8 33096.8 33691.0 34700.3 35594.8 35781.5 22500.8 24396.6 25650.4 25792.5 25988.5 02:00:37 INFO - PROCESS | 1674 | 02:00:37 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:33781.19 Median:13502.32 stddev:96789.40 (716.8%) stddev-sans-first:97028.34 02:00:37 INFO - PROCESS | 1674 | Values: 333.9 378.2 378.9 393.6 401.4 16204.8 19158.7 21381.6 21586.1 21596.4 20382.0 20482.2 21020.9 21110.6 21384.1 5277.9 5840.7 6088.8 6101.2 6117.3 5910.4 6026.1 6058.4 6086.9 6106.5 5788.3 5929.4 5990.1 5991.3 5995.9 5834.2 5964.8 5978.5 5984.5 5994.3 5076.4 5161.5 5187.7 5189.9 5191.7 24646.8 24818.5 25091.6 25371.2 25429.6 2165.2 2248.1 2347.3 2357.9 2508.5 14499.4 15216.8 15659.6 15705.3 15891.8 1758.1 1789.9 1809.6 1824.0 1837.8 89554.1 107536.9 111155.4 111552.4 112773.0 9665.5 10312.5 10451.5 10951.6 11008.9 12395.4 13346.9 13455.7 13499.2 13502.7 8246.0 8482.5 8525.2 8583.4 8610.4 8536.6 8537.7 8565.5 8689.1 8699.7 6370.4 6474.3 6511.8 6547.7 6655.6 1781.8 1819.8 1823.6 1824.4 1857.4 913.2 921.0 996.7 1005.8 1052.9 17437.7 21155.4 21288.9 21310.0 21315.6 953.7 955.7 960.1 974.3 1003.9 11308.1 11366.3 11418.7 11471.4 11484.9 516639.0 584676.0 596923.0 605199.0 606340.0 110546.0 111781.0 114947.0 117551.0 119020.0 979.4 1035.0 1050.5 1052.2 1053.4 18448.0 18640.9 18669.9 18771.5 19224.8 39289.8 53626.5 53712.0 54756.2 54796.9 68877.7 73511.6 74153.3 75067.0 75664.0 7843.2 7974.4 8043.0 8323.6 8350.7 9009.5 11837.1 12697.6 12832.5 12928.2 9790.0 10271.6 10895.4 10994.0 11098.7 6082.7 10727.4 10989.7 11001.0 11006.8 10881.9 10890.4 11057.5 11105.1 11142.9 7913.3 7979.5 7997.8 8219.7 8237.3 10036.5 11105.9 11211.4 11233.7 11258.5 02:00:37 INFO - PROCESS | 1674 | 02:00:37 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:5690.45 Median:5154.53 stddev:3218.00 (62.4%) stddev-sans-first:3210.85 02:00:37 INFO - PROCESS | 1674 | Values: 1397.7 1409.4 1595.6 1781.3 1955.7 7790.6 8561.7 8767.2 8770.6 8903.5 8525.5 8746.5 8750.0 8756.4 8756.6 2073.3 2143.0 2144.7 2300.1 2344.0 1991.3 2147.9 2174.0 2364.0 2399.3 1974.8 2091.9 2350.8 2352.0 2356.6 2126.6 2229.9 2283.9 2345.6 2350.1 1655.8 2081.5 2294.6 2371.1 2371.5 8651.6 8732.5 8741.9 8743.0 8746.4 5171.6 5340.1 5345.7 5350.3 5364.7 3497.7 3632.8 3703.9 3707.4 3711.0 1044.9 1086.8 1093.4 1099.0 1114.0 11709.2 11765.6 11791.8 11799.6 11804.1 4571.1 4700.7 4775.5 4788.2 4794.3 9941.5 9986.2 9994.4 10003.9 10005.8 5278.0 5336.0 5336.9 5351.1 5370.7 3652.9 4362.0 4808.2 4885.2 4890.8 3218.7 3553.5 3732.3 3843.3 3843.4 1117.6 1118.4 1119.7 1127.2 1158.4 868.7 891.1 903.6 940.7 945.4 5701.5 5822.4 5950.3 5956.7 6083.2 901.5 928.3 950.5 986.8 987.6 4041.2 4141.1 4230.2 4231.8 4247.9 10568.0 10714.9 10812.2 10814.8 10818.8 10426.2 10663.7 10779.8 10780.4 10795.2 8001.0 8224.3 8235.8 8243.8 8254.9 5835.5 5841.2 6017.2 6024.0 6112.0 8410.3 9769.5 9946.4 9970.3 9983.2 9875.0 9926.3 10214.9 10249.1 10282.7 6977.8 7437.9 7452.7 7549.1 7562.4 7840.4 8176.1 8392.0 8416.2 8535.3 7462.9 7531.7 7553.6 7686.2 7708.8 7143.7 7159.1 7181.0 7295.6 7364.1 6981.0 7056.5 7066.2 7067.3 7095.8 4970.7 4971.3 5019.0 5102.5 5170.3 5077.7 5139.0 5170.0 5183.1 5281.5 02:00:37 INFO - PROCESS | 1674 | 02:00:37 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:4457.70 Median:5015.30 stddev:1380.34 (27.5%) stddev-sans-first:1348.41 02:00:37 INFO - PROCESS | 1674 | Values: 296.5 301.0 309.8 315.8 319.0 5091.6 5168.9 5330.5 5343.1 5426.2 4769.6 4948.9 4969.6 5168.4 5225.9 3974.3 4008.4 4022.4 4048.5 4053.4 3178.7 3927.6 3997.4 4013.7 4045.6 3409.4 3710.9 3896.3 3971.7 4049.0 3517.5 3987.0 3995.5 3997.9 4058.5 3129.1 3307.6 3550.1 3550.4 3574.7 4476.7 4770.3 5108.8 5166.2 5182.5 4057.0 4563.7 4587.7 4589.5 4630.3 5117.8 5267.8 5431.3 5451.7 5473.3 3447.5 3475.6 3763.8 3868.5 3869.2 5085.6 5645.6 5709.7 5765.5 5769.9 3396.4 3418.6 3570.8 3797.7 3840.4 3463.5 3482.6 3909.8 3918.6 3926.4 4271.0 4494.8 4510.6 4518.8 4523.3 5197.2 5358.7 5498.5 5542.6 5556.8 1150.2 1232.8 1239.9 1278.0 1292.8 751.9 764.5 854.4 892.9 903.6 4267.2 5149.7 5150.6 5164.0 5184.6 5525.2 5567.7 5692.8 5727.4 5743.6 4755.0 4799.3 5082.5 5096.4 5101.8 2850.6 2939.5 2969.9 2994.0 2996.3 4846.9 4880.5 5049.0 5523.9 5785.8 4506.8 5721.7 5726.4 5754.2 5778.9 4939.0 5746.3 5767.1 5795.9 5812.3 4672.0 5760.4 5779.3 5784.6 5800.8 4817.7 5648.4 5678.3 5689.9 5704.8 5724.7 5950.3 6002.5 6009.2 6029.9 3437.3 3800.3 3818.0 3824.5 3844.6 4927.5 5363.5 5732.4 5750.0 5755.0 4918.6 4981.6 5293.7 5647.7 5678.0 4455.7 5547.9 5571.4 5586.7 5604.4 4472.2 4972.9 5064.0 5481.5 5532.3 4930.8 5390.8 5551.5 5598.6 5612.2 5095.5 5146.1 5180.0 5278.7 5299.2 02:00:37 INFO - PROCESS | 1674 | 02:00:37 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:18668.57 Median:25479.91 stddev:25218.44 (99.0%) stddev-sans-first:25252.99 02:00:37 INFO - PROCESS | 1674 | Values: 676.5 827.4 835.7 860.5 861.5 14702.0 14716.9 15177.1 15250.4 15333.3 14323.4 14373.9 14383.5 14436.7 14454.9 867.0 867.6 884.8 885.6 885.9 707.3 733.9 870.9 901.0 904.6 950.2 952.8 955.5 955.7 956.0 740.8 950.0 950.7 951.0 951.4 550.2 551.0 552.2 566.7 681.7 14566.5 15079.5 15113.8 15148.2 15154.5 13237.1 13516.7 13747.3 14073.9 14234.4 12336.3 12360.6 12400.9 12461.8 12798.4 4470.0 4500.1 4638.8 4641.9 4675.1 67379.0 84959.7 85887.1 85933.6 89587.6 10666.0 10859.5 11026.9 11079.4 11331.9 21630.6 21658.5 21675.7 21725.6 21770.3 6714.3 7983.1 8011.9 8043.9 8089.5 4046.7 4283.9 4392.1 4399.1 4585.3 3414.2 3512.8 3516.3 3521.9 3537.3 644.4 654.5 655.7 669.1 679.1 560.2 647.3 658.9 671.3 688.3 8438.3 9103.1 9258.6 9910.3 10063.6 536.7 623.9 641.4 712.0 738.3 4315.5 4339.5 4440.9 4487.5 4499.2 92409.0 93114.5 94209.5 95738.5 95934.5 59925.8 79215.2 82252.6 84424.0 84705.7 63890.0 65950.7 67445.4 67450.3 67488.7 9508.7 9544.3 9574.8 9613.2 9619.5 44546.0 44987.7 46321.0 46907.8 47570.3 55887.4 59644.7 62479.7 63092.7 63267.0 11173.4 12818.9 13392.7 13683.2 13924.2 14107.3 14566.1 14585.8 14759.9 14789.2 13141.5 13274.8 13349.4 13360.2 13925.0 13181.3 13225.7 13321.0 13586.9 13906.5 12806.6 12881.5 12914.2 12943.0 13422.3 10924.5 11001.0 11224.4 11397.6 11462.1 10724.4 10865.6 11316.5 11339.0 11372.5 02:00:37 INFO - PROCESS | 1674 | -------- Summary: end -------- 02:00:37 INFO - PROCESS | 1674 | 02:00:37 INFO - TEST-INFO | 1674: exit 0 02:00:37 INFO - TEST-OK | dromaeo_css | took 1115502ms 02:00:37 INFO - TEST-START | kraken 02:00:37 INFO - operating with platform_type : mac_ 02:00:37 INFO - Initialising browser for kraken test... 02:00:37 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmpfu30C1/profile http://localhost:49278/getInfo.html) 02:00:39 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 02:00:39 INFO - PROCESS | 1681 | colorDepth:24 02:00:39 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 02:00:39 INFO - PROCESS | 1681 | __metrics 02:00:39 INFO - PROCESS | 1681 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:00:39 ERROR - PROCESS | 1681 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:00:40 INFO - TEST-INFO | 1681: exit 0 02:00:40 INFO - Browser initialized. 02:00:40 INFO - Running cycle 1/1 for kraken test... 02:00:40 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmpfu30C1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:00:52 INFO - PROCESS | 1682 | RSS: Main: 241655808 02:00:52 INFO - PROCESS | 1682 | 02:00:55 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 02:00:56 INFO - PROCESS | 1682 | RSS: Main: 292319232 02:00:56 INFO - PROCESS | 1682 | 02:00:59 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 02:00:59 INFO - PROCESS | 1682 | RSS: Main: 301678592 02:00:59 INFO - PROCESS | 1682 | 02:01:03 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 02:01:03 INFO - PROCESS | 1682 | RSS: Main: 390311936 02:01:03 INFO - PROCESS | 1682 | 02:01:05 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 02:01:06 INFO - PROCESS | 1682 | RSS: Main: 289333248 02:01:06 INFO - PROCESS | 1682 | 02:01:08 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:01:09 INFO - PROCESS | 1682 | RSS: Main: 293298176 02:01:09 INFO - PROCESS | 1682 | 02:01:12 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 02:01:12 INFO - PROCESS | 1682 | RSS: Main: 331026432 02:01:12 INFO - PROCESS | 1682 | 02:01:15 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 02:01:16 INFO - PROCESS | 1682 | RSS: Main: 332603392 02:01:16 INFO - PROCESS | 1682 | 02:01:18 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 02:01:19 INFO - PROCESS | 1682 | RSS: Main: 333049856 02:01:19 INFO - PROCESS | 1682 | 02:01:21 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:01:21 INFO - PROCESS | 1682 | RSS: Main: 309751808 02:01:21 INFO - PROCESS | 1682 | 02:01:23 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:01:24 INFO - PROCESS | 1682 | RSS: Main: 317730816 02:01:24 INFO - PROCESS | 1682 | 02:01:26 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:01:27 INFO - PROCESS | 1682 | RSS: Main: 319696896 02:01:27 INFO - PROCESS | 1682 | 02:01:29 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:01:30 INFO - PROCESS | 1682 | RSS: Main: 322695168 02:01:30 INFO - PROCESS | 1682 | 02:01:33 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:01:34 INFO - PROCESS | 1682 | RSS: Main: 325148672 02:01:34 INFO - PROCESS | 1682 | 02:01:35 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 02:01:36 INFO - PROCESS | 1682 | RSS: Main: 369016832 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - PROCESS | 1682 | __start_tp_report 02:01:36 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 02:01:36 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 02:01:36 INFO - PROCESS | 1682 | |i|pagename|runs| 02:01:36 INFO - PROCESS | 1682 | |0;ai-astar;113;107;113;122;164;151;113;113;111;116 02:01:36 INFO - PROCESS | 1682 | |1;audio-beat-detection;157;124;115;162;131;141;167;141;174;117 02:01:36 INFO - PROCESS | 1682 | |2;audio-dft;182;185;183;182;190;195;181;181;182;181 02:01:36 INFO - PROCESS | 1682 | |3;audio-fft;78;73;56;105;56;57;78;90;82;72 02:01:36 INFO - PROCESS | 1682 | |4;audio-oscillator;87;89;91;71;71;70;95;70;67;100 02:01:36 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;90;88;91;87;89;87;90;87;132;129 02:01:36 INFO - PROCESS | 1682 | |6;imaging-darkroom;94;95;95;95;95;95;94;95;94;96 02:01:36 INFO - PROCESS | 1682 | |7;imaging-desaturate;85;84;94;86;84;84;85;83;85;83 02:01:36 INFO - PROCESS | 1682 | |8;json-parse-financial;57;56;58;57;57;57;56;57;57;57 02:01:36 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;66;69;78;78;71;70;70;71;71;71 02:01:36 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;85;85;85;81;83;104;85;85;122;87 02:01:36 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;138;135;137;136;135;137;136;136;157;141 02:01:36 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;216;174;166;165;166;213;219;164;164;162 02:01:36 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;66;65;67;67;65;63;66;65;65;65 02:01:36 INFO - PROCESS | 1682 | __end_tp_report 02:01:36 INFO - PROCESS | 1682 | __start_cc_report 02:01:36 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,2034 02:01:36 INFO - PROCESS | 1682 | __end_cc_report 02:01:36 INFO - PROCESS | 1682 | __startTimestamp1464426096097__endTimestamp 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - PROCESS | 1682 | ------- Summary: start ------- 02:01:36 INFO - PROCESS | 1682 | Number of tests: 14 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:122.30 Median:114.50 stddev:19.18 (16.7%) stddev-sans-first:20.04 02:01:36 INFO - PROCESS | 1682 | Values: 113.0 107.0 113.0 122.0 164.0 151.0 113.0 113.0 111.0 116.0 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:142.90 Median:149.00 stddev:21.26 (14.3%) stddev-sans-first:21.93 02:01:36 INFO - PROCESS | 1682 | Values: 157.0 124.0 115.0 162.0 131.0 141.0 167.0 141.0 174.0 117.0 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:184.20 Median:182.50 stddev:4.69 (2.6%) stddev-sans-first:4.90 02:01:36 INFO - PROCESS | 1682 | Values: 182.0 185.0 183.0 182.0 190.0 195.0 181.0 181.0 182.0 181.0 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:74.70 Median:75.50 stddev:15.81 (20.9%) stddev-sans-first:16.73 02:01:36 INFO - PROCESS | 1682 | Values: 78.0 73.0 56.0 105.0 56.0 57.0 78.0 90.0 82.0 72.0 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:79.00 stddev:12.45 (15.8%) stddev-sans-first:13.02 02:01:36 INFO - PROCESS | 1682 | Values: 87.0 89.0 91.0 71.0 71.0 70.0 95.0 70.0 67.0 100.0 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:97.00 Median:88.50 stddev:17.73 (20.0%) stddev-sans-first:18.62 02:01:36 INFO - PROCESS | 1682 | Values: 90.0 88.0 91.0 87.0 89.0 87.0 90.0 87.0 132.0 129.0 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 02:01:36 INFO - PROCESS | 1682 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 94.0 96.0 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.00 stddev:3.20 (3.8%) stddev-sans-first:3.39 02:01:36 INFO - PROCESS | 1682 | Values: 85.0 84.0 94.0 86.0 84.0 84.0 85.0 83.0 85.0 83.0 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:0.57 (1.0%) stddev-sans-first:0.60 02:01:36 INFO - PROCESS | 1682 | Values: 57.0 56.0 58.0 57.0 57.0 57.0 56.0 57.0 57.0 57.0 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.00 stddev:3.75 (5.3%) stddev-sans-first:3.41 02:01:36 INFO - PROCESS | 1682 | Values: 66.0 69.0 78.0 78.0 71.0 70.0 70.0 71.0 71.0 71.0 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:85.00 stddev:12.84 (15.1%) stddev-sans-first:13.48 02:01:36 INFO - PROCESS | 1682 | Values: 85.0 85.0 85.0 81.0 83.0 104.0 85.0 85.0 122.0 87.0 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:138.80 Median:137.00 stddev:6.63 (4.8%) stddev-sans-first:7.03 02:01:36 INFO - PROCESS | 1682 | Values: 138.0 135.0 137.0 136.0 135.0 137.0 136.0 136.0 157.0 141.0 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.90 Median:170.00 stddev:24.47 (14.4%) stddev-sans-first:22.41 02:01:36 INFO - PROCESS | 1682 | Values: 216.0 174.0 166.0 165.0 166.0 213.0 219.0 164.0 164.0 162.0 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:65.50 stddev:1.17 (1.8%) stddev-sans-first:1.22 02:01:36 INFO - PROCESS | 1682 | Values: 66.0 65.0 67.0 67.0 65.0 63.0 66.0 65.0 65.0 65.0 02:01:36 INFO - PROCESS | 1682 | -------- Summary: end -------- 02:01:36 INFO - PROCESS | 1682 | 02:01:36 INFO - TEST-INFO | 1682: exit 0 02:01:36 INFO - TEST-OK | kraken | took 59199ms 02:01:36 INFO - SUITE-END | took 1174s 02:01:37 INFO - Completed test suite (00:19:35) 02:01:37 INFO - javascript benchmark 02:01:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3394.1514621344663, 3457.844397002133, 3462.7229821614264, 3486.163320803008, 3509.719222462203, 5552.028395030874, 5595.944040559593, 5653.349864765543, 5680.517156041737, 5710.5717071219615, 5580.888382232353, 5610.579206559504, 5641.746121424536, 5705.201271821938, 5740.913886291709, 5087.6184286178495, 5116.718580478072, 5173.637845350827, 5178.300929374524, 5186.351706036746, 4475.440569928759, 5258.2638430619745, 5296.390915044762, 5317.7607007684555, 5347.919781203285, 4257.701960862741, 5102.362204724391, 5104.540591346797, 5158.613104017214, 5167.3282473278605, 4516.796744146488, 4949.282354058641, 5137.974310128426, 5138.177891537336, 5155.9484405155845, 5125.026245013472, 5141.691498510064, 5157.638965272433, 5201.219817027439, 5223.817166399196, 4853.417589889201, 5302.337207849019, 5594.916076258859, 5650.208970744099, 5650.886982260373, 6140.846478838065, 6160.938390616081, 6163.7842675506145, 6198.876022479571, 6230.59501132425, 6199.070139479069, 6266.87466250677, 6272.153259310026, 6281.560290779647, 6288.402601752826, 5640.210370548127, 5703.62926409782, 5817.214676018725, 5828.300603927513, 5830.475257226827, 6178.598391104564, 6237.1891654084675, 6248.218972628421, 6288.371162883766, 6299.968500157562, 6014.6090504117965, 6031.849203769941, 6046.444712337435, 6079.75680972765, 6091.390860913962, 6081.781570238115, 6104.4200800923845, 6118.284172697687, 6158.4320739251025, 6196.938030619636, 6101.307919489667, 6158.876822463526, 6174.289956654952, 6195.4424101831055, 6200.596961197508, 6197.597156184834, 6203.534734894811, 6215.875682486416, 6228.252609686867, 6228.719707613168, 4827.348307978448, 4881.536254055859, 4898.510148985144, 4900.142475066849, 4936.6297527685565, 4346.174226896879, 4373.169097871394, 4380.102079073782, 4401.1417773534495, 4447.332900064951, 5609.15862620602, 5613.382527921925, 5630.493255606933, 5658.0, 5674.375818659944, 6095.75616975316, 6120.0205129226715, 6171.259448866075, 6201.410865967726, 6203.16257305267, 5601.0, 5621.971890140523, 5662.235598194274, 5682.744276507485, 5714.771409143587, 5337.973310133502, 5351.86620334487, 5355.892882142335, 5357.919631205535, 5375.408705042442, 6270.435660790551, 6278.497720182374, 6281.246250449975, 6284.183056202664, 6292.182016337847, 6072.119542666377, 6101.641069425165, 6121.696863452088, 6156.29202641702, 6190.319064902767, 6249.50003999688, 6281.026440901667, 6288.213973253343, 6289.339619340087, 6301.149344838388, 6036.698165091816, 6129.724162412613, 6155.692215389123, 6166.290876549075, 6187.226596675416, 5060.51925067118, 5158.535731784157, 6050.273967123973, 6057.061155520725, 6157.6613286269685, 6027.849303767441, 6040.395960403925, 6045.274567051982, 6046.3046749624355, 6102.725377358119, 6022.337542870193, 6024.277086749618, 6033.78881739137, 6058.242719660043, 6059.848503787441, 5702.37273899879, 5798.739056742541, 5840.707964601838, 5857.1799948008165, 5962.9105563415715, 6010.879782404415, 6043.335233124265, 6050.748533429907, 6057.172954896699, 6108.205933228652, 5679.403662615366, 5867.354590994902, 5889.882202356014, 5899.17411562373, 5907.054871220584], "value": 5627.499184725841, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [518.20455600653, 519.1900635009388, 526.3236740788088, 540.4163503416311, 585.721782153477, 30683.07950761478, 41852.790736046314, 42649.57350426499, 42715.0, 44736.55263447364, 44165.8958526037, 44816.103677926425, 44877.55122448775, 44940.32589511152, 44944.3258351125, 8554.828903421927, 9541.427514349127, 9767.0, 9768.925418203991, 9781.951090244538, 8622.223999840013, 11547.769044619103, 11708.180427370087, 11725.296482211095, 12021.0, 10020.498975051254, 11743.236689615147, 11827.704307392341, 11828.58599949002, 11840.940795296012, 8669.0, 8808.0, 8823.250023747989, 11718.941405293004, 11816.704582385422, 8721.0, 11372.886271137348, 11676.883231167665, 11706.941465292704, 11947.0, 40844.0, 41102.79448602738, 41228.38157427641, 41271.587284127076, 41332.38001429981, 3495.860165593373, 3624.873129440457, 3631.527901372831, 3796.943045854287, 3841.673457756094, 32093.839530802197, 32256.0, 32519.512207316908, 32634.184145396317, 33348.1662958428, 2778.9583156252675, 3268.96731032689, 3272.345530893807, 3279.7868138570916, 3430.588329400488, 481037.0, 483690.0, 486016.0, 486049.99999999645, 488070.0, 62402.68798656068, 62680.37319626837, 62709.68645156745, 62798.686006569675, 63007.42481438001, 66477.6676116626, 66538.6692266152, 66554.0, 66643.66678166579, 66717.33282667208, 15381.84636152293, 15784.447544335893, 16597.91701041487, 16844.410445634345, 16862.494125176265, 11773.88226117728, 12838.614841554769, 12982.026348023935, 13042.739145217043, 13073.673158171121, 17574.736378954327, 17581.187010078327, 17625.942443453176, 17633.707618009576, 17634.294628214986, 5077.593792496591, 7092.716291348392, 7154.105736782903, 7187.7843664690145, 7277.745278915212, 856.9400141990001, 888.2893685051932, 928.865314529403, 983.8720966274338, 1064.0, 29004.12987610375, 30226.0, 30344.54483182754, 31027.0, 31100.533491997638, 1056.9365838049587, 1061.856649352343, 1066.722652110457, 1146.5184622458587, 1182.1193211057816, 15839.683206335809, 15894.966827156199, 15934.681306373808, 15942.28259728315, 16357.836421635631, 662014.0, 667617.0, 667718.0, 668912.0, 669778.0, 49040.0, 60278.69860650669, 60540.69729651324, 60692.0, 60846.39153608407, 1252.3925895940279, 1286.7104901397297, 1387.6322774464775, 1409.1474657832068, 1430.5207755402057, 31175.90884319038, 31604.36791264116, 31768.523472147474, 32086.83956580202, 32489.187770305933, 51447.742761285954, 51701.74149129381, 52262.73868630632, 52315.47684523258, 52331.0, 443531.0, 445337.0, 445519.0, 445634.0, 447249.0, 31802.978717022772, 40477.595224047385, 41723.37414938839, 42506.36240456458, 43911.56088439075, 47706.28440573325, 47954.280685790436, 48535.271970919755, 49399.50600493949, 49579.50420495749, 33515.0, 40040.799796000836, 40217.0, 40562.594374057066, 40990.79504602458, 38689.03277418087, 38820.80589597034, 39257.803710981265, 39776.2044759109, 39998.40002399908, 38302.61697383102, 38773.612263877, 38781.41827872528, 38958.80520597379, 39210.80394598123, 33096.834515827264, 33691.0, 34700.30599388049, 35594.822025889705, 35781.46327805137, 22500.774992250525, 24396.6340504889, 25650.39338810171, 25792.484150317265, 25988.480230395664], "value": 20610.115507414637, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.91318257253124, 378.160483726128, 378.88254641061275, 393.5828022296364, 401.43798681845414, 16204.83795162048, 19158.712619310703, 21381.57236855264, 21586.13655453784, 21596.352109436702, 20381.980900954968, 20482.18071277147, 21020.8948955255, 21110.57778844422, 21384.144634214652, 5277.947220527803, 5840.707964601774, 6088.756449742027, 6101.237345331833, 6117.296510901258, 5910.379410161936, 6026.0960855871535, 6058.363871793465, 6086.908696369558, 6106.511479081662, 5788.305403351604, 5929.436703513159, 5990.101484777275, 5991.281046274453, 5995.910061349084, 5834.183214349994, 5964.821055368324, 5978.491828194608, 5984.461398474136, 5994.280686317648, 5076.36545431821, 5161.509656582619, 5187.740612969336, 5189.870253243661, 5191.740412979336, 24646.753532464805, 24818.5036299273, 25091.62362564563, 25371.238862834143, 25429.618555721678, 2165.231342873273, 2248.111995761665, 2347.2840783561005, 2357.8821058946983, 2508.4732206236713, 14499.420023199165, 15216.847831521654, 15659.608509787233, 15705.293261803246, 15891.841081589151, 1758.0594382005565, 1789.937352192667, 1809.6018875847292, 1824.015031882795, 1837.8345948864533, 89554.10445895523, 107536.92463075348, 111155.44422277917, 111552.44223778829, 112773.0, 9665.468399237969, 10312.484375781181, 10451.529681164366, 10951.56193752241, 11008.889911100787, 12395.442205100791, 13346.933265333611, 13455.730885382238, 13499.190048597116, 13502.662433439242, 8246.0, 8482.491050536988, 8525.19010693983, 8583.39916205872, 8610.354223433142, 8536.615852286537, 8537.701180458655, 8565.48607083577, 8689.087645797226, 8699.695510657224, 6370.362963703593, 6474.287828338879, 6511.837204069936, 6547.738090476328, 6655.633940133339, 1781.8218178182078, 1819.7543331650322, 1823.5867202917846, 1824.434425328126, 1857.3870622694478, 913.159892898535, 920.9677661281823, 996.7109538233976, 1005.8139244239838, 1052.8894466081103, 17437.7384339235, 21155.365339039854, 21288.893555532122, 21310.0, 21315.573688526452, 953.6948176583427, 955.7323949294208, 960.1023043454393, 974.2595627323144, 1003.8996100389902, 11308.09535237179, 11366.261193022427, 11418.710857890886, 11471.4264286787, 11484.885151148548, 516639.0, 584676.0, 596923.0, 605199.0, 606340.0, 110546.0, 111781.0, 114947.0, 117551.0, 119020.0, 979.4368238262884, 1034.9637762678421, 1050.495762034228, 1052.2213561964245, 1053.4100903494068, 18448.0, 18640.906795465937, 18669.906650466663, 18771.530711732317, 19224.8077519223, 39289.821305360885, 53626.463735362144, 53712.0, 54756.178657319375, 54796.90406191933, 68877.65561172162, 73511.63244183744, 74153.25846741679, 75067.0, 75664.0, 7843.156753746584, 7974.368458408595, 8043.034835819739, 8323.625436855235, 8350.749477515685, 9009.504477253551, 11837.112216583619, 12697.55558555446, 12832.486700531874, 12928.224306541637, 9790.020997900438, 10271.640492582725, 10895.400752958663, 10994.010539051524, 11098.667039988813, 6082.695865206633, 10727.40999245049, 10989.725256868642, 11001.0, 11006.779864402506, 10881.945590271998, 10890.401027943539, 11057.502412391623, 11105.111591072533, 11142.944285278521, 7913.327367173854, 7979.48133371329, 7997.8400431992195, 8219.671213151647, 8237.258646721824, 10036.548355324174, 11105.94447027792, 11211.383373914514, 11233.71915702114, 11258.549658013588], "value": 9597.544380667685, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1397.7414178376994, 1409.4362255097956, 1595.6170519075422, 1781.3230972230551, 1955.7359756432877, 7790.649420776064, 8561.700340488083, 8767.210951014407, 8770.56147192641, 8903.46579205248, 8525.488470691764, 8746.475211487315, 8750.03749587545, 8756.387052906299, 8756.562171891395, 2073.2536286936693, 2143.024923659731, 2144.7211862457857, 2300.056976639578, 2343.9648405273933, 1991.273185287367, 2147.9033443495, 2173.978260217393, 2363.9881800590974, 2399.2922087983998, 1974.7729011163756, 2091.922659830189, 2350.8001819845335, 2351.953380745569, 2356.552255071531, 2126.5746850629935, 2229.9108035678555, 2283.851549649276, 2345.6129738593113, 2350.095213342867, 1655.7747267022437, 2081.469225347527, 2294.644330128816, 2371.0871314544024, 2371.4664200554907, 8651.567421628893, 8732.476051436934, 8741.868871966926, 8743.0, 8746.431481953652, 5171.560417364529, 5340.06548853949, 5345.65253258537, 5350.2777125088005, 5364.731763411853, 3497.702695270905, 3632.8183590820354, 3703.907402314937, 3707.443883417509, 3711.0, 1044.937303761769, 1086.8261078227445, 1093.4095588382263, 1098.9945050274778, 1113.9777204455866, 11709.180357374988, 11765.647030589096, 11791.764164716658, 11799.646010619696, 11804.055675545937, 4571.108633816374, 4700.694454860423, 4775.450823155312, 4788.186008378584, 4794.256890182028, 9941.502924853728, 9986.20110391167, 9994.400335979864, 10003.949980250198, 10005.79988400228, 5278.049951008778, 5336.0, 5336.919946200733, 5351.117065684196, 5370.650907690988, 3652.8538858445895, 4361.978190109029, 4808.158572249842, 4885.2427873679635, 4890.804367825318, 3218.710316071518, 3553.5202747629255, 3732.272206919679, 3843.3082045231567, 3843.423486476995, 1117.620009196877, 1118.3829838178538, 1119.7032786311634, 1127.1771606727134, 1158.368689064462, 868.7306934850217, 891.1266958380824, 903.6430609909049, 940.6895724410928, 945.4164274839616, 5701.543876489871, 5822.417758224144, 5950.256217972754, 5956.672383018976, 6083.209182806296, 901.486152892858, 928.3362395886974, 950.4534892436876, 986.8026394720941, 987.5753426026733, 4041.2119636671155, 4141.130362623882, 4230.196262710137, 4231.788410579458, 4247.893802654959, 10568.048875601078, 10714.946425267824, 10812.189085818594, 10814.78370432587, 10818.783624327469, 10426.165906727443, 10663.73340666474, 10779.784404312026, 10780.407077610807, 10795.244332896778, 8001.0, 8224.342052635895, 8235.752927412188, 8243.752687419386, 8254.917450825415, 5835.533157347487, 5841.153032810304, 6017.15759793638, 6023.969880150747, 6111.96944015277, 8410.327173826201, 9769.462679552693, 9946.403215806786, 9970.30207885441, 9983.201343892326, 9875.0, 9926.305158638826, 10214.897851021691, 10249.12882405004, 10282.691519254435, 6977.79066628002, 7437.925620743723, 7452.701891924262, 7549.0563679540055, 7562.432817538816, 7840.431913617185, 8176.141505141874, 8391.992960844738, 8416.158384161536, 8535.274501667427, 7462.925370746223, 7531.736389226351, 7553.5845528494265, 7686.192949740197, 7708.8458230836195, 7143.714251430092, 7159.140903091662, 7181.0, 7295.598742069237, 7364.079490063742, 6980.965095174492, 7056.470764692566, 7066.187388450188, 7067.2579379164445, 7095.822604434724, 4970.65205435616, 4971.328870602567, 5018.974905125451, 5102.540771330598, 5170.3278573785165, 5077.669951453144, 5139.023585518739, 5170.043541944752, 5183.144781111075, 5281.498257665516], "value": 4545.167542247394, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.54332328214537, 300.9638843338799, 309.77386507849263, 315.8436573895922, 318.96172459304887, 5091.618128640348, 5168.914527949135, 5330.546903513201, 5343.141419340654, 5426.155107263529, 4769.642276829234, 4948.921452989744, 4969.577585905203, 5168.379794424665, 5225.8954820903555, 3974.284628766821, 4008.418779277003, 4022.376531637586, 4048.4736984192086, 4053.3919912013284, 3178.6821317868144, 3927.56796752357, 3997.4403583498333, 4013.7190396672245, 4045.59544045596, 3409.437442821931, 3710.9072273193115, 3896.298666240076, 3971.709194511781, 4048.9797551012202, 3517.4547945068557, 3987.0, 3995.5005624296964, 3997.8600748973645, 4058.5129784425762, 3129.076922307902, 3307.6361600223954, 3550.1302180965827, 3550.414181660023, 3574.7140228781636, 4476.664250181249, 4770.282698228635, 5108.846734597931, 5166.225066240057, 5182.507661772163, 4057.0, 4563.70335928167, 4587.7017993830295, 4589.518100599423, 4630.282306242538, 5117.795288188468, 5267.841964741064, 5431.321084864392, 5451.727413629303, 5473.31583552056, 3447.465642825366, 3475.6350583188655, 3763.814398464502, 3868.5357757069332, 3869.2261547690573, 5085.644004919694, 5645.633033852787, 5709.714514274269, 5765.4811067004175, 5769.942300577024, 3396.352768907078, 3418.6068602110577, 3570.785752854785, 3797.6771974381936, 3840.4431357453013, 3463.5497385339745, 3482.6343233960574, 3909.8045097745, 3918.5885482024546, 3926.411038344214, 4271.0, 4494.775261236925, 4510.594046535827, 4518.796654150571, 4523.276275795923, 5197.167543688699, 5358.678479291255, 5498.4981833265765, 5542.584306177053, 5556.805511807067, 1150.2350936627217, 1232.7795482472288, 1239.9274627447194, 1278.0, 1292.8319318488536, 751.898493703354, 764.506893053977, 854.3805740837843, 892.8660700894918, 903.6204793986542, 4267.1892340455215, 5149.716765577854, 5150.587952963754, 5164.0, 5184.611154163377, 5525.171224316385, 5567.696433892934, 5692.840473537227, 5727.39862314451, 5743.59794814359, 4754.9791338680925, 4799.3041009053495, 5082.517160869711, 5096.388433388017, 5101.770420331094, 2850.586664933573, 2939.5149800283143, 2969.88629264026, 2993.985030074836, 2996.3408050228913, 4846.878828029327, 4880.511948805091, 5048.974755126201, 5523.944760552424, 5785.82642520725, 4506.752128632957, 5721.741216932268, 5726.398728133569, 5754.1944127822135, 5778.88442231153, 4938.98750756087, 5746.310442746897, 5767.106098554815, 5795.94204057954, 5812.331581868139, 4671.976640116914, 5760.366359700346, 5779.335376431596, 5784.566157538117, 5800.8259752207505, 4817.710937343771, 5648.429964366285, 5678.3186017678145, 5689.914651280152, 5704.828855134187, 5724.740557077436, 5950.3454619992635, 6002.519798416203, 6009.167204002822, 6029.909551356821, 3437.346904088315, 3800.315943130263, 3818.0, 3824.5028146340796, 3844.615538446133, 4927.507249275044, 5363.517283444508, 5732.398098199629, 5750.0, 5755.0, 4918.5981995131215, 4981.62637802159, 5293.676580854786, 5647.745851436777, 5677.971610141923, 4455.665825063068, 5547.8640723321005, 5571.387147413862, 5586.737371364604, 5604.355499117653, 4472.239719247671, 4972.8508144755715, 5063.987202559577, 5481.451854814487, 5532.253145825262, 4930.778114984906, 5390.757415916371, 5551.500364967173, 5598.580106491948, 5612.214289999485, 5095.515925987025, 5146.073706732824, 5179.98990196908, 5278.736063196901, 5299.231611416246], "value": 4014.820006267836, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.4723515657786, 827.3794654009491, 835.6824406725443, 860.466510763326, 861.4960248254766, 14702.04436711613, 14716.852831471682, 15177.089374637515, 15250.38998440061, 15333.310001049938, 14323.355449004794, 14373.92813035936, 14383.496577619786, 14436.711265774678, 14454.855451445483, 866.9826603467911, 867.6052396159764, 884.8230353929208, 885.5660726244126, 885.853834117373, 707.3457052226695, 733.8605664923674, 870.9477431354139, 900.9594568244412, 904.6019751309435, 950.1875896108832, 952.8142012307603, 955.498363359235, 955.6941778630841, 956.0105291023792, 740.8481261341466, 950.0309684122157, 950.6577632052456, 950.9952450237739, 951.4243882451156, 550.243415303957, 550.9669419834783, 552.215853488048, 566.6600039976023, 681.686424244848, 14566.490172844005, 15079.47221847241, 15113.773293400607, 15148.1668508232, 15154.469593564172, 13237.139585926883, 13516.662083447894, 13747.312634368242, 14073.929630351886, 14234.430622775077, 12336.259824410565, 12360.629181124581, 12400.937995310056, 12461.813072803916, 12798.424070916832, 4469.977650111761, 4500.077484115748, 4638.814447422099, 4641.883952901171, 4675.088357770236, 67379.0, 84959.72560411599, 85887.14112858854, 85933.57033214856, 89587.55206223927, 10666.0, 10859.511321990529, 11026.889731102747, 11079.390633515073, 11331.886681133248, 21630.56738865214, 21658.458538536663, 21675.67486487704, 21725.56548869014, 21770.346889593337, 6714.328567143247, 7983.081945576333, 8011.919880801234, 8043.919560804434, 8089.514629122272, 4046.6560342371226, 4283.892902677396, 4392.121575684876, 4399.076193999232, 4585.266357382803, 3414.2488652496413, 3512.7716698415034, 3516.2967406518796, 3521.9119522012156, 3537.327907697529, 644.44899610833, 654.4873099975641, 655.6787174284566, 669.0566301514841, 679.0561120043144, 560.2464684998752, 647.3235468934975, 658.9209294884645, 671.2806885337, 688.3460712323313, 8438.282745966539, 9103.089691030977, 9258.629654813867, 9910.256730745223, 10063.597456101821, 536.7074944155445, 623.8614528485532, 641.3810672700866, 711.9786406407816, 738.2506755643027, 4315.525292217853, 4339.500957389878, 4440.866773996785, 4487.483939346952, 4499.235130027903, 92409.0, 93114.53442732742, 94209.52895235617, 95738.52130739302, 95934.52032739791, 59925.80148397008, 79215.20784792193, 82252.58873705713, 84424.0, 84705.72941405883, 63890.0, 65950.68098638, 67445.42914411152, 67450.32549674538, 67488.6625566869, 9508.667196648084, 9544.284178686488, 9574.760630984003, 9613.182879455324, 9619.470929098967, 44546.0, 44987.74577091798, 46321.0, 46907.76546117248, 47570.28644570265, 55887.44112558822, 59644.70177649084, 62479.6876015617, 63092.68453657702, 63267.0, 11173.441327933733, 12818.872807865682, 13392.665183370495, 13683.24742139192, 13924.164550127025, 14107.29463526799, 14566.126032438087, 14585.781213282022, 14759.92620036893, 14789.186594737394, 13141.474341026253, 13274.800877986645, 13349.399277032751, 13360.198388096745, 13925.025248232527, 13181.340932953506, 13225.669358266121, 13321.0, 13586.93206533961, 13906.51327203543, 12806.61580152597, 12881.549145780227, 12914.22514649124, 12943.0, 13422.261775602436, 10924.50839712199, 11001.0, 11224.438778061227, 11397.601083962021, 11462.14033947474, 10724.410157441416, 10865.619703310345, 11316.547338106384, 11339.0, 11372.54509819598], "value": 7342.102724107879, "unit": "score"}], "name": "dromaeo_css", "value": 7282.382644473883, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 113.0, 122.0, 164.0, 151.0, 113.0, 113.0, 111.0, 116.0], "value": 122.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 124.0, 115.0, 162.0, 131.0, 141.0, 167.0, 141.0, 174.0, 117.0], "value": 142.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 183.0, 182.0, 190.0, 195.0, 181.0, 181.0, 182.0, 181.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 56.0, 105.0, 56.0, 57.0, 78.0, 90.0, 82.0, 72.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 91.0, 71.0, 71.0, 70.0, 95.0, 70.0, 67.0, 100.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 91.0, 87.0, 89.0, 87.0, 90.0, 87.0, 132.0, 129.0], "value": 97.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 96.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 94.0, 86.0, 84.0, 84.0, 85.0, 83.0, 85.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 58.0, 57.0, 57.0, 57.0, 56.0, 57.0, 57.0, 57.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 78.0, 78.0, 71.0, 70.0, 70.0, 71.0, 71.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 81.0, 83.0, 104.0, 85.0, 85.0, 122.0, 87.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 135.0, 137.0, 136.0, 135.0, 137.0, 136.0, 136.0, 157.0, 141.0], "value": 138.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [216.0, 174.0, 166.0, 165.0, 166.0, 213.0, 219.0, 164.0, 164.0, 162.0], "value": 180.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 67.0, 67.0, 65.0, 63.0, 66.0, 65.0, 65.0, 65.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1486.0, "alertThreshold": 2.0}]} 02:01:37 INFO - Return code: 0 02:01:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:01:37 ERROR - # TBPL SUCCESS # 02:01:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:01:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:01:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:01:37 INFO - 2016-05-28 02:01:37.412 screenresolution[1686:10501] starting screenresolution argv=screenresolution get 02:01:37 INFO - 2016-05-28 02:01:37.414 screenresolution[1686:10501] Display 0: 1600x1200x32@60 02:01:37 INFO - 2016-05-28 02:01:37.420 screenresolution[1687:10505] starting screenresolution argv=screenresolution list 02:01:37 INFO - Available Modes on Display 0 02:01:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:01:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:01:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:01:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:01:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:01:37 INFO - Intel Iris: 02:01:37 INFO - Chipset Model: Intel Iris 02:01:37 INFO - Type: GPU 02:01:37 INFO - Bus: Built-In 02:01:37 INFO - VRAM (Dynamic, Max): 1536 MB 02:01:37 INFO - Vendor: Intel (0x8086) 02:01:37 INFO - Device ID: 0x0a2e 02:01:37 INFO - Revision ID: 0x0009 02:01:37 INFO - Displays: 02:01:37 INFO - Display: 02:01:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:01:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:01:37 INFO - Display Serial Number: 1600x1200 60 02:01:37 INFO - Main Display: Yes 02:01:37 INFO - Mirror: Off 02:01:37 INFO - Online: Yes 02:01:37 INFO - Rotation: Supported 02:01:37 INFO - Return code: 0 02:01:37 INFO - Running post-action listener: _resource_record_post_action 02:01:37 INFO - ##### 02:01:37 INFO - ##### Finished run-tests step (success) 02:01:37 INFO - ##### 02:01:37 INFO - Running post-run listener: _resource_record_post_run 02:01:37 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 31591936; Write bytes: 695311360; Read time: 198; Write time: 1943 02:01:37 INFO - TinderboxPrint: CPU usage
53.5% 02:01:37 INFO - TinderboxPrint: I/O read bytes / time
31,591,936 / 198 02:01:37 INFO - TinderboxPrint: I/O write bytes / time
695,311,360 / 1,943 02:01:37 INFO - TinderboxPrint: CPU idle
2,251.7 (46.5%) 02:01:37 INFO - TinderboxPrint: CPU system
961.2 (19.9%) 02:01:37 INFO - TinderboxPrint: CPU user
1,628.8 (33.6%) 02:01:37 INFO - TinderboxPrint: Swap in / out
425,984,000 / 0 02:01:37 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 393878016; Write bytes: 387937792; Read time: 29423; Write time: 838 02:01:37 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 29810176; Write bytes: 294036992; Read time: 183; Write time: 1069 02:01:38 INFO - Running post-run listener: _upload_blobber_files 02:01:38 INFO - Blob upload gear active. 02:01:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:01:38 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 02:01:38 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'] 02:01:38 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 02:01:38 INFO - (blobuploader) - INFO - Open directory for files ... 02:01:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:01:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:01:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:01:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:01:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:01:39 INFO - (blobuploader) - INFO - Done attempting. 02:01:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:01:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:01:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:01:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:01:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:01:40 INFO - (blobuploader) - INFO - Done attempting. 02:01:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:01:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:01:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:01:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:01:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:01:44 INFO - (blobuploader) - INFO - Done attempting. 02:01:44 INFO - (blobuploader) - INFO - Iteration through files over. 02:01:44 INFO - Return code: 0 02:01:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:01:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:01:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0905f22feae094966a18b3e34cbafdffc7603ae3db4f6669fabc6322b327c0f8f93c29c1413f1ee76c4a16b2883a5f49912929c1e2ddd148660c846220cb29c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/954e9ae8e59539977886bcb2c0dc9d93cca58ec9e2a15430ce7fdd5f33d64f11619b39cae267bceca73316e9a435c21a9d26aba86f46a2f7de8f450471e813c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ee8a962ebe4f009ff9844d41db927333641deba53961be405be419688c12be064af97a424d87bc75f44dc1ff7e539664d21319b0b72447f2a5415422e654efd"} 02:01:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:01:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:01:44 INFO - Contents: 02:01:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0905f22feae094966a18b3e34cbafdffc7603ae3db4f6669fabc6322b327c0f8f93c29c1413f1ee76c4a16b2883a5f49912929c1e2ddd148660c846220cb29c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/954e9ae8e59539977886bcb2c0dc9d93cca58ec9e2a15430ce7fdd5f33d64f11619b39cae267bceca73316e9a435c21a9d26aba86f46a2f7de8f450471e813c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ee8a962ebe4f009ff9844d41db927333641deba53961be405be419688c12be064af97a424d87bc75f44dc1ff7e539664d21319b0b72447f2a5415422e654efd"} 02:01:44 INFO - Running post-run listener: copy_logs_to_upload_dir 02:01:44 INFO - Copying logs to upload dir... 02:01:44 INFO - mkdir: /builds/slave/test/build/upload/logs 02:01:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1292.466601 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2016-05-28 02:01:44.475074) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 02:01:44.478455) ========= 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.JWJxr50QVH/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.O8dIQaUOfs/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0905f22feae094966a18b3e34cbafdffc7603ae3db4f6669fabc6322b327c0f8f93c29c1413f1ee76c4a16b2883a5f49912929c1e2ddd148660c846220cb29c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/954e9ae8e59539977886bcb2c0dc9d93cca58ec9e2a15430ce7fdd5f33d64f11619b39cae267bceca73316e9a435c21a9d26aba86f46a2f7de8f450471e813c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ee8a962ebe4f009ff9844d41db927333641deba53961be405be419688c12be064af97a424d87bc75f44dc1ff7e539664d21319b0b72447f2a5415422e654efd"} build_url:https://queue.taskcluster.net/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010947 build_url: 'https://queue.taskcluster.net/v1/task/N7oEDUU0SaaL1RbXSVBkmA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0905f22feae094966a18b3e34cbafdffc7603ae3db4f6669fabc6322b327c0f8f93c29c1413f1ee76c4a16b2883a5f49912929c1e2ddd148660c846220cb29c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/954e9ae8e59539977886bcb2c0dc9d93cca58ec9e2a15430ce7fdd5f33d64f11619b39cae267bceca73316e9a435c21a9d26aba86f46a2f7de8f450471e813c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ee8a962ebe4f009ff9844d41db927333641deba53961be405be419688c12be064af97a424d87bc75f44dc1ff7e539664d21319b0b72447f2a5415422e654efd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 02:01:44.508569) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 02:01:44.508920) ========= 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.JWJxr50QVH/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.O8dIQaUOfs/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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.004661 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 02:01:44.557829) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 02:01:44.558170) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 02:01:44.558522) ========= ========= Total master_lag: 0.17 =========