builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0100 starttime: 1463039031.15 results: success (0) buildid: 20160511234012 builduid: eacc19691b5e433c9faf3f81cef51004 revision: c3ff04109a8a6e439be36ae44133b2ba68fc79f6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 00:43:51.146605) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 00:43:51.147066) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 00:43:51.147341) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 00:43:51.164837) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:43:51.165122) ========= 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.mWQWltQxfn/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.jyqN1XpwQi/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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-12 00:43:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.05M=0.001s 2016-05-12 00:43:51 (8.05 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118138 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:43:51.300601) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:43:51.301182) ========= 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.mWQWltQxfn/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.jyqN1XpwQi/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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.020619 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:43:51.351399) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:43:51.351853) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c3ff04109a8a6e439be36ae44133b2ba68fc79f6 --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 c3ff04109a8a6e439be36ae44133b2ba68fc79f6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mWQWltQxfn/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.jyqN1XpwQi/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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-12 00:43:51,422 truncating revision to first 12 chars 2016-05-12 00:43:51,422 Setting DEBUG logging. 2016-05-12 00:43:51,423 attempt 1/10 2016-05-12 00:43:51,423 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c3ff04109a8a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 00:43:52,045 unpacking tar archive at: fx-team-c3ff04109a8a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.862369 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:43:52.231261) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 00:43:52.231583) ========= script_repo_revision: c3ff04109a8a6e439be36ae44133b2ba68fc79f6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 00:43:52.231979) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 00:43:52.232257) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 00:43:52.245395) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 13 secs) (at 2016-05-12 00:43:52.245722) ========= /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.mWQWltQxfn/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.jyqN1XpwQi/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 00:43:52 INFO - MultiFileLogger online at 20160512 00:43:52 in /builds/slave/test 00:43:52 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 00:43:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:43:52 INFO - {'append_to_log': False, 00:43:52 INFO - 'base_work_dir': '/builds/slave/test', 00:43:52 INFO - 'blob_upload_branch': 'Fx-Team', 00:43:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:43:52 INFO - 'branch': 'Fx-Team', 00:43:52 INFO - 'buildbot_json_path': 'buildprops.json', 00:43:52 INFO - 'config_files': ('talos/mac_config.py',), 00:43:52 INFO - 'default_actions': ('clobber', 00:43:52 INFO - 'read-buildbot-config', 00:43:52 INFO - 'download-and-extract', 00:43:52 INFO - 'populate-webroot', 00:43:52 INFO - 'create-virtualenv', 00:43:52 INFO - 'install', 00:43:52 INFO - 'run-tests'), 00:43:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:43:52 INFO - 'download_minidump_stackwalk': True, 00:43:52 INFO - 'download_symbols': 'ondemand', 00:43:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:43:52 INFO - 'tooltool.py': '/tools/tooltool.py', 00:43:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:43:52 INFO - '/tools/misc-python/virtualenv.py')}, 00:43:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:43:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:43:52 INFO - 'installer_path': 'installer.exe', 00:43:52 INFO - 'log_level': 'info', 00:43:52 INFO - 'log_name': 'talos', 00:43:52 INFO - 'log_to_console': True, 00:43:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 00:43:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 00:43:52 INFO - 'opt_config_files': (), 00:43:52 INFO - 'pip_index': False, 00:43:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:43:52 INFO - 'cmd': ('bash', 00:43:52 INFO - '-c', 00:43:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:43:52 INFO - 'enabled': True, 00:43:52 INFO - 'halt_on_failure': False, 00:43:52 INFO - 'name': 'check_screen_resolution'},), 00:43:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:43:52 INFO - 'cmd': ('bash', 00:43:52 INFO - '-c', 00:43:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:43:52 INFO - 'enabled': True, 00:43:52 INFO - 'halt_on_failure': False, 00:43:52 INFO - 'name': 'check_screen_resolution'},), 00:43:52 INFO - 'run_cmd_checks_enabled': True, 00:43:52 INFO - 'sps_profile': False, 00:43:52 INFO - 'sps_profile_interval': 0, 00:43:52 INFO - 'suite': 'dromaeojs', 00:43:52 INFO - 'system_bits': '32', 00:43:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:43:52 INFO - 'title': 't-yosemite-r7-0100', 00:43:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:43:52 INFO - 'use_talos_json': True, 00:43:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:43:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:43:52 INFO - 'work_dir': 'build'} 00:43:52 INFO - ##### 00:43:52 INFO - ##### Running clobber step. 00:43:52 INFO - ##### 00:43:52 INFO - Running pre-action listener: _resource_record_pre_action 00:43:52 INFO - Running main action method: clobber 00:43:52 INFO - rmtree: /builds/slave/test/build 00:43:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:43:54 INFO - Running post-action listener: _resource_record_post_action 00:43:54 INFO - ##### 00:43:54 INFO - ##### Finished clobber step (success) 00:43:54 INFO - ##### 00:43:54 INFO - ##### 00:43:54 INFO - ##### Running read-buildbot-config step. 00:43:54 INFO - ##### 00:43:54 INFO - Running pre-action listener: _resource_record_pre_action 00:43:54 INFO - Running main action method: read_buildbot_config 00:43:54 INFO - Using buildbot properties: 00:43:54 INFO - { 00:43:54 INFO - "project": "", 00:43:54 INFO - "product": "firefox", 00:43:54 INFO - "script_repo_revision": "production", 00:43:54 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 00:43:54 INFO - "repository": "", 00:43:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 00:43:54 INFO - "buildid": "20160511234012", 00:43:54 INFO - "pgo_build": "False", 00:43:54 INFO - "basedir": "/builds/slave/test", 00:43:54 INFO - "buildnumber": 14, 00:43:54 INFO - "slavename": "t-yosemite-r7-0100", 00:43:54 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 00:43:54 INFO - "platform": "yosemite_r7", 00:43:54 INFO - "scheduler": "tests-fx-team-macosx64-talos", 00:43:54 INFO - "branch": "fx-team", 00:43:54 INFO - "repo_path": "integration/fx-team", 00:43:54 INFO - "revision": "c3ff04109a8a6e439be36ae44133b2ba68fc79f6", 00:43:54 INFO - "stage_platform": "macosx64", 00:43:54 INFO - "builduid": "eacc19691b5e433c9faf3f81cef51004", 00:43:54 INFO - "slavebuilddir": "test" 00:43:54 INFO - } 00:43:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 00:43:54 INFO - Running post-action listener: _resource_record_post_action 00:43:54 INFO - ##### 00:43:54 INFO - ##### Finished read-buildbot-config step (success) 00:43:54 INFO - ##### 00:43:54 INFO - ##### 00:43:54 INFO - ##### Running download-and-extract step. 00:43:54 INFO - ##### 00:43:54 INFO - Running pre-action listener: _resource_record_pre_action 00:43:54 INFO - Running main action method: download_and_extract 00:43:54 INFO - mkdir: /builds/slave/test/build/tests 00:43:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:43:54 INFO - https://queue.taskcluster.net/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 00:43:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 00:43:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 00:43:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/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 00:43:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/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 00:43:55 INFO - Downloaded 1199 bytes. 00:43:55 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 00:43:55 INFO - Using the following test package requirements: 00:43:55 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 00:43:55 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:43:55 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 00:43:55 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:43:55 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 00:43:55 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:43:55 INFO - u'jsshell-mac.zip'], 00:43:55 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:43:55 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 00:43:55 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 00:43:55 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:43:55 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 00:43:55 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:43:55 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 00:43:55 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:43:55 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 00:43:55 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:43:55 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 00:43:55 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 00:43: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')]} 00:43:55 INFO - https://queue.taskcluster.net/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 00:43:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:43:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:43:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/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 00:43:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/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 00:43:56 INFO - Downloaded 18150585 bytes. 00:43:56 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'] 00:43:56 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 00:43:59 INFO - Return code: 0 00:43:59 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 00:43:59 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')]} 00:43:59 INFO - https://queue.taskcluster.net/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 00:43:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:43:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:43:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/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 00:43:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/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 00:44:00 INFO - Downloaded 18150585 bytes. 00:44:00 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'] 00:44:00 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 00:44:04 INFO - Return code: 0 00:44:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:44:04 INFO - https://queue.taskcluster.net/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 00:44:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 00:44:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 00:44:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/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 00:44:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/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 00:44:05 INFO - Downloaded 10840152 bytes. 00:44:05 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'] 00:44:05 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 00:44:05 INFO - Return code: 0 00:44:05 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')]} 00:44:05 INFO - https://queue.taskcluster.net/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 00:44:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 00:44:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 00:44:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 00:44:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/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 00:44:36 WARNING - Socket error when accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg: The read operation timed out 00:44:36 INFO - retry: attempt #1 caught exception: The read operation timed out 00:44:36 INFO - retry: Failed, sleeping 30 seconds before retrying 00:45:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #2 00:45:10 INFO - Downloaded 109797911 bytes. 00:45:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 00:45:10 INFO - mkdir: /builds/slave/test/properties 00:45:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:45:10 INFO - Writing to file /builds/slave/test/properties/build_url 00:45:10 INFO - Contents: 00:45:10 INFO - build_url:https://queue.taskcluster.net/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 00:45:11 INFO - Running post-action listener: _resource_record_post_action 00:45:11 INFO - Running post-action listener: set_extra_try_arguments 00:45:11 INFO - ##### 00:45:11 INFO - ##### Finished download-and-extract step (success) 00:45:11 INFO - ##### 00:45:11 INFO - ##### 00:45:11 INFO - ##### Running populate-webroot step. 00:45:11 INFO - ##### 00:45:11 INFO - Running pre-action listener: _resource_record_pre_action 00:45:11 INFO - Running main action method: populate_webroot 00:45:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:45:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:45:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:45:11 INFO - '--tppagecycles', 00:45:11 INFO - '7'], 00:45:11 INFO - 'tests': ['tsvgm']}}, 00:45:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:45:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:45:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:45:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:45:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:45:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:45:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:45:11 INFO - 'talos_options': ['--disable-e10s'], 00:45:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:45:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:45:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:45:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:45:11 INFO - 'talos_options': ['--disable-e10s'], 00:45:11 INFO - 'tests': ['damp', 'tps']}, 00:45:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:45:11 INFO - 'tests': ['damp', 'tps']}, 00:45:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:45:11 INFO - 'tests': ['dromaeo_dom']}, 00:45:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:45:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:45:11 INFO - 'tests': ['a11yr', 00:45:11 INFO - 'ts_paint', 00:45:11 INFO - 'tpaint', 00:45:11 INFO - 'sessionrestore', 00:45:11 INFO - 'sessionrestore_no_auto_restore', 00:45:11 INFO - 'tabpaint']}, 00:45:11 INFO - 'other-e10s': {'tests': ['a11yr', 00:45:11 INFO - 'ts_paint', 00:45:11 INFO - 'tpaint', 00:45:11 INFO - 'sessionrestore', 00:45:11 INFO - 'sessionrestore_no_auto_restore', 00:45:11 INFO - 'tabpaint']}, 00:45:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:45:11 INFO - 'ts_paint', 00:45:11 INFO - 'tpaint', 00:45:11 INFO - 'sessionrestore', 00:45:11 INFO - 'sessionrestore_no_auto_restore', 00:45:11 INFO - 'tabpaint']}, 00:45:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:45:11 INFO - 'ts_paint', 00:45:11 INFO - 'tpaint', 00:45:11 INFO - 'sessionrestore', 00:45:11 INFO - 'sessionrestore_no_auto_restore', 00:45:11 INFO - 'tabpaint']}, 00:45:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:45:11 INFO - 'tests': ['a11yr', 00:45:11 INFO - 'ts_paint', 00:45:11 INFO - 'tpaint', 00:45:11 INFO - 'sessionrestore', 00:45:11 INFO - 'sessionrestore_no_auto_restore', 00:45:11 INFO - 'tabpaint']}, 00:45:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:45:11 INFO - 'tests': ['a11yr', 00:45:11 INFO - 'ts_paint', 00:45:11 INFO - 'tpaint', 00:45:11 INFO - 'sessionrestore', 00:45:11 INFO - 'sessionrestore_no_auto_restore', 00:45:11 INFO - 'tabpaint']}, 00:45:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:45:11 INFO - 'tests': ['tsvgx', 00:45:11 INFO - 'tsvgr_opacity', 00:45:11 INFO - 'tart', 00:45:11 INFO - 'tscrollx', 00:45:11 INFO - 'cart']}, 00:45:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:45:11 INFO - 'tsvgr_opacity', 00:45:11 INFO - 'tart', 00:45:11 INFO - 'tscrollx', 00:45:11 INFO - 'cart']}, 00:45:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:45:11 INFO - 'talos_options': ['--disable-e10s'], 00:45:11 INFO - 'tests': ['tp5o']}, 00:45:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:45:11 INFO - 'tests': ['tp5o']}, 00:45:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:45:11 INFO - 'talos_options': ['--disable-e10s', 00:45:11 INFO - '--xperf_path', 00:45:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:45:11 INFO - 'tests': ['tp5n']}, 00:45:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:45:11 INFO - 'talos_options': ['--xperf_path', 00:45:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:45:11 INFO - 'tests': ['tp5n']}}, 00:45:11 INFO - 'talos.zip': {'path': '', 00:45:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:45:11 INFO - Running post-action listener: _resource_record_post_action 00:45:11 INFO - ##### 00:45:11 INFO - ##### Finished populate-webroot step (success) 00:45:11 INFO - ##### 00:45:11 INFO - ##### 00:45:11 INFO - ##### Running create-virtualenv step. 00:45:11 INFO - ##### 00:45:11 INFO - Running pre-action listener: _resource_record_pre_action 00:45:11 INFO - Running main action method: create_virtualenv 00:45:11 INFO - Creating virtualenv /builds/slave/test/build/venv 00:45:11 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 00:45:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:45:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:45:11 INFO - Using real prefix '/tools/python27' 00:45:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:45:12 INFO - Installing distribute.............................................................................................................................................................................................done. 00:45:15 INFO - Installing pip.................done. 00:45:15 INFO - Return code: 0 00:45:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101af8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101afe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdc9c82ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10199cd30>, '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 0x1017c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101993b20>, '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.mWQWltQxfn/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.jyqN1XpwQi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 00:45:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 00:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 00:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mWQWltQxfn/Render', 00:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:15 INFO - 'HOME': '/Users/cltbld', 00:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:15 INFO - 'LOGNAME': 'cltbld', 00:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:15 INFO - 'MOZ_NO_REMOTE': '1', 00:45:15 INFO - 'NO_EM_RESTART': '1', 00:45:15 INFO - 'PAGER': '/bin/cat', 00:45:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:15 INFO - 'PWD': '/builds/slave/test', 00:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:15 INFO - 'SHELL': '/bin/bash', 00:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyqN1XpwQi/Listeners', 00:45:15 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 00:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:15 INFO - 'USER': 'cltbld', 00:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:15 INFO - 'XPC_FLAGS': '0x0', 00:45:15 INFO - 'XPC_SERVICE_NAME': '0', 00:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:15 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 00:45:15 INFO - Cleaning up... 00:45:15 INFO - Return code: 0 00:45:15 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 00:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101af8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101afe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdc9c82ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10199cd30>, '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 0x1017c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101993b20>, '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.mWQWltQxfn/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.jyqN1XpwQi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 00:45:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 00:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 00:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mWQWltQxfn/Render', 00:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:15 INFO - 'HOME': '/Users/cltbld', 00:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:15 INFO - 'LOGNAME': 'cltbld', 00:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:15 INFO - 'MOZ_NO_REMOTE': '1', 00:45:15 INFO - 'NO_EM_RESTART': '1', 00:45:15 INFO - 'PAGER': '/bin/cat', 00:45:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:15 INFO - 'PWD': '/builds/slave/test', 00:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:15 INFO - 'SHELL': '/bin/bash', 00:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyqN1XpwQi/Listeners', 00:45:15 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 00:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:15 INFO - 'USER': 'cltbld', 00:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:15 INFO - 'XPC_FLAGS': '0x0', 00:45:15 INFO - 'XPC_SERVICE_NAME': '0', 00:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:15 INFO - Downloading/unpacking psutil>=0.7.1 00:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:45:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:45:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:45:19 INFO - Installing collected packages: psutil 00:45:19 INFO - Running setup.py install for psutil 00:45:19 INFO - building 'psutil._psutil_osx' extension 00:45:19 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 00:45:19 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 00:45:19 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 00:45:19 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 00:45:19 INFO - building 'psutil._psutil_posix' extension 00:45:19 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 00:45:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 00:45:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 00:45:19 INFO - ^ 00:45:19 INFO - 1 warning generated. 00:45:19 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 00:45:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:45:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:45:20 INFO - Successfully installed psutil 00:45:20 INFO - Cleaning up... 00:45:20 INFO - Return code: 0 00:45:20 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 00:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:20 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101af8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101afe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdc9c82ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10199cd30>, '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 0x1017c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101993b20>, '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.mWQWltQxfn/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.jyqN1XpwQi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 00:45:20 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.0.0'] in /builds/slave/test/build 00:45:20 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.0.0 00:45:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mWQWltQxfn/Render', 00:45:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:20 INFO - 'HOME': '/Users/cltbld', 00:45:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:20 INFO - 'LOGNAME': 'cltbld', 00:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:20 INFO - 'MOZ_NO_REMOTE': '1', 00:45:20 INFO - 'NO_EM_RESTART': '1', 00:45:20 INFO - 'PAGER': '/bin/cat', 00:45:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:20 INFO - 'PWD': '/builds/slave/test', 00:45:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:20 INFO - 'SHELL': '/bin/bash', 00:45:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyqN1XpwQi/Listeners', 00:45:20 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 00:45:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:20 INFO - 'USER': 'cltbld', 00:45:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:20 INFO - 'XPC_FLAGS': '0x0', 00:45:20 INFO - 'XPC_SERVICE_NAME': '0', 00:45:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:20 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 00:45: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 00:45: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 00:45: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 00:45: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 00:45: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 00:45: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 00:45:22 INFO - Downloading mozsystemmonitor-0.0.tar.gz 00:45:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 00:45:23 INFO - Installing collected packages: mozsystemmonitor 00:45:23 INFO - Running setup.py install for mozsystemmonitor 00:45:23 INFO - Successfully installed mozsystemmonitor 00:45:23 INFO - Cleaning up... 00:45:23 INFO - Return code: 0 00:45:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:23 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 0x101af8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101afe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdc9c82ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10199cd30>, '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 0x1017c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101993b20>, '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.mWQWltQxfn/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.jyqN1XpwQi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 00:45:23 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 00:45:23 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 00:45:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mWQWltQxfn/Render', 00:45:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:23 INFO - 'HOME': '/Users/cltbld', 00:45:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:23 INFO - 'LOGNAME': 'cltbld', 00:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:23 INFO - 'MOZ_NO_REMOTE': '1', 00:45:23 INFO - 'NO_EM_RESTART': '1', 00:45:23 INFO - 'PAGER': '/bin/cat', 00:45:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:23 INFO - 'PWD': '/builds/slave/test', 00:45:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:23 INFO - 'SHELL': '/bin/bash', 00:45:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyqN1XpwQi/Listeners', 00:45:23 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 00:45:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:23 INFO - 'USER': 'cltbld', 00:45:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:23 INFO - 'XPC_FLAGS': '0x0', 00:45:23 INFO - 'XPC_SERVICE_NAME': '0', 00:45:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:23 INFO - Downloading/unpacking blobuploader==1.2.4 00:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:26 INFO - Downloading blobuploader-1.2.4.tar.gz 00:45:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:45:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:45:26 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 00:45:26 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 00:45:26 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 00:45:26 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 00:45:26 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 00:45:26 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 00:45:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:45:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:45:26 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 00:45:26 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 00:45:26 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 00:45:26 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 00:45:26 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 00:45:26 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 00:45:27 INFO - Downloading docopt-0.6.1.tar.gz 00:45:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:45:27 INFO - Installing collected packages: blobuploader, requests, docopt 00:45:27 INFO - Running setup.py install for blobuploader 00:45:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:45:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:45:27 INFO - Running setup.py install for requests 00:45:27 INFO - Running setup.py install for docopt 00:45:28 INFO - Successfully installed blobuploader requests docopt 00:45:28 INFO - Cleaning up... 00:45:28 INFO - Return code: 0 00:45:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:45:28 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')]} 00:45:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:28 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')]} 00:45:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:28 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 0x101af8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101afe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdc9c82ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10199cd30>, '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 0x1017c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101993b20>, '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.mWQWltQxfn/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.jyqN1XpwQi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 00:45:28 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 00:45:28 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 00:45:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mWQWltQxfn/Render', 00:45:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:28 INFO - 'HOME': '/Users/cltbld', 00:45:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:28 INFO - 'LOGNAME': 'cltbld', 00:45:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:28 INFO - 'MOZ_NO_REMOTE': '1', 00:45:28 INFO - 'NO_EM_RESTART': '1', 00:45:28 INFO - 'PAGER': '/bin/cat', 00:45:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:28 INFO - 'PWD': '/builds/slave/test', 00:45:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:28 INFO - 'SHELL': '/bin/bash', 00:45:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyqN1XpwQi/Listeners', 00:45:28 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 00:45:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:28 INFO - 'USER': 'cltbld', 00:45:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:28 INFO - 'XPC_FLAGS': '0x0', 00:45:28 INFO - 'XPC_SERVICE_NAME': '0', 00:45:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:45:28 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-vvgzTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:45:28 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-bG2mfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:45:28 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-83CLEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:45:28 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-WozMnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:45:28 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-W90zPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:45:29 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-QaPu3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:45:29 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-pydjVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:45:29 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-JSJlI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:45:29 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-BDEDtQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:45:29 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-SbxVee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:45:29 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-1i4yod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:45:29 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-iIzk3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:45:29 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-htm14J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:45:29 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-xkJKvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:45:29 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-EUw5gi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:45:30 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-MMei3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:45:30 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-F2ajyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:45:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:45:30 INFO - Running setup.py install for manifestparser 00:45:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:45:30 INFO - Running setup.py install for mozcrash 00:45:30 INFO - Running setup.py install for mozdebug 00:45:30 INFO - Running setup.py install for mozdevice 00:45:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:45:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:45:30 INFO - Running setup.py install for mozfile 00:45:31 INFO - Running setup.py install for mozhttpd 00:45:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:45:31 INFO - Running setup.py install for mozinfo 00:45:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:45:31 INFO - Running setup.py install for mozInstall 00:45:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:45:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:45:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:45:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:45:31 INFO - Running setup.py install for mozleak 00:45:31 INFO - Running setup.py install for mozlog 00:45:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:45:31 INFO - Running setup.py install for moznetwork 00:45:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:45:31 INFO - Running setup.py install for mozprocess 00:45:32 INFO - Running setup.py install for mozprofile 00:45:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:45:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:45:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:45:32 INFO - Running setup.py install for mozrunner 00:45:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:45:32 INFO - Running setup.py install for mozscreenshot 00:45:32 INFO - Running setup.py install for moztest 00:45:32 INFO - Running setup.py install for mozversion 00:45:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:45:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:45:32 INFO - Cleaning up... 00:45:32 INFO - Return code: 0 00:45:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:32 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 0x101af8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101afe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdc9c82ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10199cd30>, '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 0x1017c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101993b20>, '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.mWQWltQxfn/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.jyqN1XpwQi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 00:45:32 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 00:45:32 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 00:45:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mWQWltQxfn/Render', 00:45:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:32 INFO - 'HOME': '/Users/cltbld', 00:45:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:32 INFO - 'LOGNAME': 'cltbld', 00:45:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:32 INFO - 'MOZ_NO_REMOTE': '1', 00:45:32 INFO - 'NO_EM_RESTART': '1', 00:45:32 INFO - 'PAGER': '/bin/cat', 00:45:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:32 INFO - 'PWD': '/builds/slave/test', 00:45:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:32 INFO - 'SHELL': '/bin/bash', 00:45:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyqN1XpwQi/Listeners', 00:45:32 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 00:45:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:32 INFO - 'USER': 'cltbld', 00:45:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:32 INFO - 'XPC_FLAGS': '0x0', 00:45:32 INFO - 'XPC_SERVICE_NAME': '0', 00:45:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:45:33 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-1rauLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:45:33 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)) 00:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:45:33 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-ZHYqy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:45:33 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)) 00:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:45:33 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-L5roud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:45:33 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)) 00:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:45:33 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-lDkeIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:45:33 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)) 00:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:45:33 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-PUruAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:45:33 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)) 00:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:45:33 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-cT0J0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:45:33 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)) 00:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:45:33 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-F8Sw1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:45:33 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)) 00:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:45:33 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-CifpVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:45:34 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)) 00:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:45:34 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-_lefQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:45:34 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)) 00:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:45:34 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-o9rvQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 00:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:45:34 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-3nKKO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:45:34 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)) 00:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:45:34 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-B1kodz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:45:34 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)) 00:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:45:34 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-DEWCr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:45:34 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)) 00:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:45:34 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-DxUIIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:45:34 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)) 00:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:45:34 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-ytn5Zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:45:34 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)) 00:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:45:34 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-FArXAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:45:34 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)) 00:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:45:34 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-iXpiPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:45:35 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)) 00:45:35 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)) 00:45:35 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)) 00:45:35 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)) 00:45:35 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)) 00:45:35 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)) 00:45:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:45:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:37 INFO - Downloading blessings-1.6.tar.gz 00:45:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:45:37 INFO - Installing collected packages: blessings 00:45:37 INFO - Running setup.py install for blessings 00:45:38 INFO - Successfully installed blessings 00:45:38 INFO - Cleaning up... 00:45:38 INFO - Return code: 0 00:45:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:45:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:45:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:45:38 INFO - Reading from file tmpfile_stdout 00:45:38 INFO - Current package versions: 00:45:38 INFO - blessings == 1.6 00:45:38 INFO - blobuploader == 1.2.4 00:45:38 INFO - docopt == 0.6.1 00:45:38 INFO - manifestparser == 1.1 00:45:38 INFO - mozInstall == 1.12 00:45:38 INFO - mozcrash == 0.17 00:45:38 INFO - mozdebug == 0.1 00:45:38 INFO - mozdevice == 0.48 00:45:38 INFO - mozfile == 1.2 00:45:38 INFO - mozhttpd == 0.7 00:45:38 INFO - mozinfo == 0.9 00:45:38 INFO - mozleak == 0.1 00:45:38 INFO - mozlog == 3.1 00:45:38 INFO - moznetwork == 0.27 00:45:38 INFO - mozprocess == 0.22 00:45:38 INFO - mozprofile == 0.28 00:45:38 INFO - mozrunner == 6.11 00:45:38 INFO - mozscreenshot == 0.1 00:45:38 INFO - mozsystemmonitor == 0.0 00:45:38 INFO - moztest == 0.7 00:45:38 INFO - mozversion == 1.4 00:45:38 INFO - psutil == 3.1.1 00:45:38 INFO - requests == 1.2.3 00:45:38 INFO - wsgiref == 0.1.2 00:45:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:38 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 0x101af8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101afe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdc9c82ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10199cd30>, '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 0x1017c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101993b20>, '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.mWQWltQxfn/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.jyqN1XpwQi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 00:45:38 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 00:45:38 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 00:45:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mWQWltQxfn/Render', 00:45:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:38 INFO - 'HOME': '/Users/cltbld', 00:45:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:38 INFO - 'LOGNAME': 'cltbld', 00:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:38 INFO - 'MOZ_NO_REMOTE': '1', 00:45:38 INFO - 'NO_EM_RESTART': '1', 00:45:38 INFO - 'PAGER': '/bin/cat', 00:45:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:38 INFO - 'PWD': '/builds/slave/test', 00:45:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:38 INFO - 'SHELL': '/bin/bash', 00:45:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyqN1XpwQi/Listeners', 00:45:38 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 00:45:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:38 INFO - 'USER': 'cltbld', 00:45:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:38 INFO - 'XPC_FLAGS': '0x0', 00:45:38 INFO - 'XPC_SERVICE_NAME': '0', 00:45:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:38 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)) 00:45:38 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)) 00:45:38 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)) 00:45:38 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)) 00:45:38 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)) 00:45:38 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)) 00:45:38 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)) 00:45:38 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)) 00:45:38 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)) 00:45:38 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)) 00:45:38 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)) 00:45:38 INFO - Cleaning up... 00:45:38 INFO - Return code: 0 00:45:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:45:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101af8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101afe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdc9c82ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10199cd30>, '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 0x1017c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101993b20>, '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.mWQWltQxfn/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.jyqN1XpwQi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 00:45:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 00:45:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 00:45:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mWQWltQxfn/Render', 00:45:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:38 INFO - 'HOME': '/Users/cltbld', 00:45:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:38 INFO - 'LOGNAME': 'cltbld', 00:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:38 INFO - 'MOZ_NO_REMOTE': '1', 00:45:38 INFO - 'NO_EM_RESTART': '1', 00:45:38 INFO - 'PAGER': '/bin/cat', 00:45:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:38 INFO - 'PWD': '/builds/slave/test', 00:45:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:38 INFO - 'SHELL': '/bin/bash', 00:45:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyqN1XpwQi/Listeners', 00:45:38 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 00:45:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:38 INFO - 'USER': 'cltbld', 00:45:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:38 INFO - 'XPC_FLAGS': '0x0', 00:45:38 INFO - 'XPC_SERVICE_NAME': '0', 00:45:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:38 INFO - Downloading/unpacking jsonschema 00:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:45:41 INFO - Downloading/unpacking functools32 (from jsonschema) 00:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:42 INFO - Downloading functools32-3.2.3-2.tar.gz 00:45:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:45:42 INFO - warning: no files found matching '*.txt' 00:45:42 INFO - no previously-included directories found matching 'build' 00:45:42 INFO - no previously-included directories found matching 'dist' 00:45:42 INFO - no previously-included directories found matching '.git*' 00:45:42 INFO - Installing collected packages: jsonschema, functools32 00:45:42 INFO - Running setup.py install for functools32 00:45:42 INFO - warning: no files found matching '*.txt' 00:45:42 INFO - no previously-included directories found matching 'build' 00:45:42 INFO - no previously-included directories found matching 'dist' 00:45:42 INFO - no previously-included directories found matching '.git*' 00:45:42 INFO - Successfully installed jsonschema functools32 00:45:42 INFO - Cleaning up... 00:45:42 INFO - Return code: 0 00:45:42 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 00:45:42 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')]} 00:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:45:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:42 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')]} 00:45:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:45:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:45:42 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 0x101af8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101afe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdc9c82ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10199cd30>, '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 0x1017c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101993b20>, '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.mWQWltQxfn/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.jyqN1XpwQi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 00:45:42 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 00:45:42 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 00:45:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mWQWltQxfn/Render', 00:45:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:42 INFO - 'HOME': '/Users/cltbld', 00:45:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:42 INFO - 'LOGNAME': 'cltbld', 00:45:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:42 INFO - 'MOZ_NO_REMOTE': '1', 00:45:42 INFO - 'NO_EM_RESTART': '1', 00:45:42 INFO - 'PAGER': '/bin/cat', 00:45:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:42 INFO - 'PWD': '/builds/slave/test', 00:45:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:42 INFO - 'SHELL': '/bin/bash', 00:45:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyqN1XpwQi/Listeners', 00:45:42 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 00:45:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:42 INFO - 'USER': 'cltbld', 00:45:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:42 INFO - 'XPC_FLAGS': '0x0', 00:45:42 INFO - 'XPC_SERVICE_NAME': '0', 00:45:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:45:42 INFO - Downloading/unpacking flake8 00:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:45:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:45:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 00:45:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 00:45:45 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 00:45:45 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 00:45:45 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 00:45:45 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 00:45:45 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 00:45:45 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 00:45:45 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 00:45:45 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 00:45:45 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 00:45:45 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 00:45:45 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 00:45:45 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 00:45:45 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 00:45:45 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 00:45:46 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 00:45:46 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 00:45:46 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 00:45:46 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 00:45:46 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 00:45:46 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 00:45:46 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 00:45:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 00:45:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:45:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:45:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:45:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:45:46 INFO - no previously-included directories found matching 'docs/_build' 00:45:46 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 00:45:46 INFO - Running setup.py install for pep8 00:45:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:45:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:45:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:45:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:45:46 INFO - no previously-included directories found matching 'docs/_build' 00:45:47 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 00:45:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 00:45:47 INFO - Cleaning up... 00:45:47 INFO - Return code: 0 00:45:47 INFO - Running post-action listener: _resource_record_post_action 00:45:47 INFO - Running post-action listener: _start_resource_monitoring 00:45:47 INFO - Starting resource monitoring. 00:45:47 INFO - ##### 00:45:47 INFO - ##### Finished create-virtualenv step (success) 00:45:47 INFO - ##### 00:45:47 INFO - ##### 00:45:47 INFO - ##### Running install step. 00:45:47 INFO - ##### 00:45:47 INFO - Running pre-action listener: _resource_record_pre_action 00:45:47 INFO - Running main action method: install 00:45:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:45:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:45:47 INFO - Reading from file tmpfile_stdout 00:45:47 INFO - Detecting whether we're running mozinstall >=1.0... 00:45:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:45:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:45:47 INFO - Reading from file tmpfile_stdout 00:45:47 INFO - Output received: 00:45:47 INFO - Usage: mozinstall [options] installer 00:45:47 INFO - Options: 00:45:47 INFO - -h, --help show this help message and exit 00:45:47 INFO - -d DEST, --destination=DEST 00:45:47 INFO - Directory to install application into. [default: 00:45:47 INFO - "/builds/slave/test"] 00:45:47 INFO - --app=APP Application being installed. [default: firefox] 00:45:47 INFO - mkdir: /builds/slave/test/build/application 00:45:47 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'] 00:45:47 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 00:46:20 INFO - Reading from file tmpfile_stdout 00:46:20 INFO - Output received: 00:46:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 00:46:20 INFO - Running post-action listener: _resource_record_post_action 00:46:20 INFO - ##### 00:46:20 INFO - ##### Finished install step (success) 00:46:20 INFO - ##### 00:46:20 INFO - ##### 00:46:20 INFO - ##### Running run-tests step. 00:46:20 INFO - ##### 00:46:20 INFO - Running pre-action listener: _resource_record_pre_action 00:46:20 INFO - Running main action method: run_tests 00:46:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:46:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:46:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:46:20 INFO - 2016-05-12 00:46:20.880 screenresolution[2488:30960] starting screenresolution argv=screenresolution get 00:46:20 INFO - 2016-05-12 00:46:20.882 screenresolution[2488:30960] Display 0: 1600x1200x32@60 00:46:20 INFO - 2016-05-12 00:46:20.888 screenresolution[2489:30962] starting screenresolution argv=screenresolution list 00:46:20 INFO - Available Modes on Display 0 00:46:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:46:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:46:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:46:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:46:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:46:21 INFO - Intel Iris: 00:46:21 INFO - Chipset Model: Intel Iris 00:46:21 INFO - Type: GPU 00:46:21 INFO - Bus: Built-In 00:46:21 INFO - VRAM (Dynamic, Max): 1536 MB 00:46:21 INFO - Vendor: Intel (0x8086) 00:46:21 INFO - Device ID: 0x0a2e 00:46:21 INFO - Revision ID: 0x0009 00:46:21 INFO - Displays: 00:46:21 INFO - Display: 00:46:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:46:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:46:21 INFO - Display Serial Number: 1600x1200 60 00:46:21 INFO - Main Display: Yes 00:46:21 INFO - Mirror: Off 00:46:21 INFO - Online: Yes 00:46:21 INFO - Rotation: Supported 00:46:21 INFO - Return code: 0 00:46:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:46:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:46:21 INFO - Python 2.7.3 00:46:21 INFO - Return code: 0 00:46:21 INFO - grabbing minidump binary from tooltool 00:46: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')]} 00:46:21 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 0x10199cd30>, '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 0x1017c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101993b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:46:21 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 00:46:21 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 00:46:21 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 00:46:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:46:21 INFO - Return code: 0 00:46:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:46:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:46:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:46:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:46:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:46:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 00:46:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 00:46:22 INFO - Return code: 0 00:46:22 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/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0100', '--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 00:46:22 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/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0100 --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 00:46:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mWQWltQxfn/Render', 00:46:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:46:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:46:22 INFO - 'HOME': '/Users/cltbld', 00:46:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:46:22 INFO - 'LOGNAME': 'cltbld', 00:46:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:46:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:46:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:22 INFO - 'MOZ_NO_REMOTE': '1', 00:46:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:46:22 INFO - 'NO_EM_RESTART': '1', 00:46:22 INFO - 'PAGER': '/bin/cat', 00:46:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:46:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:46:22 INFO - 'PWD': '/builds/slave/test', 00:46:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:46:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:46:22 INFO - 'SHELL': '/bin/bash', 00:46:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jyqN1XpwQi/Listeners', 00:46:22 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 00:46:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:46:22 INFO - 'USER': 'cltbld', 00:46:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:46:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:46:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:22 INFO - 'XPC_FLAGS': '0x0', 00:46:22 INFO - 'XPC_SERVICE_NAME': '0', 00:46:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:46:22 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/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0100', '--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 00:46:22 INFO - mozversion application_buildid: 20160511234012 00:46:22 INFO - mozversion application_changeset: c3ff04109a8a6e439be36ae44133b2ba68fc79f6 00:46:22 INFO - mozversion application_display_name: Nightly 00:46:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:46:22 INFO - mozversion application_name: Firefox 00:46:22 INFO - mozversion application_remotingname: firefox 00:46:22 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 00:46:22 INFO - mozversion application_vendor: Mozilla 00:46:22 INFO - mozversion application_version: 49.0a1 00:46:22 INFO - mozversion platform_buildid: 20160511234012 00:46:22 INFO - mozversion platform_changeset: c3ff04109a8a6e439be36ae44133b2ba68fc79f6 00:46:22 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 00:46:22 INFO - mozversion platform_version: 49.0a1 00:46:22 INFO - using testdate: 1463039182 00:46:22 INFO - actual date: 1463039182 00:46:22 INFO - starting webserver on 'localhost:62702' 00:46:22 INFO - SUITE-START | Running 2 tests 00:46:22 INFO - TEST-START | dromaeo_css 00:46:22 INFO - operating with platform_type : mac_ 00:46:22 INFO - Initialising browser for dromaeo_css test... 00:46:22 INFO - TEST-INFO | started process 2507 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmp0XHcXW/profile http://localhost:62702/getInfo.html) 00:46:24 INFO - PROCESS | 2507 | __metrics Screen width/height:1600/1200 00:46:24 INFO - PROCESS | 2507 | colorDepth:24 00:46:24 INFO - PROCESS | 2507 | Browser inner width/height: 1024/689 00:46:24 INFO - PROCESS | 2507 | __metrics 00:46:24 INFO - PROCESS | 2507 | 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" 00:46:24 ERROR - PROCESS | 2507 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 00:46:25 INFO - TEST-INFO | 2507: exit 0 00:46:25 INFO - Browser initialized. 00:46:25 INFO - Running cycle 1/1 for dromaeo_css test... 00:46:25 INFO - TEST-INFO | started process 2509 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmp0XHcXW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:46:37 INFO - PROCESS | 2509 | RSS: Main: 239034368 00:46:37 INFO - PROCESS | 2509 | 00:47:28 INFO - PROCESS | 2509 | 1463039248558 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] 00:49:40 INFO - PROCESS | 2509 | Cycle 1(1): loaded http://localhost:62702/tests/dromaeo/cssquery-dojo.html (next: http://localhost:62702/tests/dromaeo/cssquery-ext.html) 00:49:42 INFO - PROCESS | 2509 | RSS: Main: 443248640 00:49:42 INFO - PROCESS | 2509 | 00:52:45 INFO - PROCESS | 2509 | Cycle 1(1): loaded http://localhost:62702/tests/dromaeo/cssquery-ext.html (next: http://localhost:62702/tests/dromaeo/cssquery-jquery.html) 00:52:46 INFO - PROCESS | 2509 | RSS: Main: 373432320 00:52:46 INFO - PROCESS | 2509 | 00:55:48 INFO - PROCESS | 2509 | Cycle 1(1): loaded http://localhost:62702/tests/dromaeo/cssquery-jquery.html (next: http://localhost:62702/tests/dromaeo/cssquery-mootools.html) 00:55:49 INFO - PROCESS | 2509 | RSS: Main: 371601408 00:55:49 INFO - PROCESS | 2509 | 00:58:51 INFO - PROCESS | 2509 | Cycle 1(1): loaded http://localhost:62702/tests/dromaeo/cssquery-mootools.html (next: http://localhost:62702/tests/dromaeo/cssquery-prototype.html) 00:58:52 INFO - PROCESS | 2509 | RSS: Main: 371544064 00:58:52 INFO - PROCESS | 2509 | 01:01:55 INFO - PROCESS | 2509 | Cycle 1(1): loaded http://localhost:62702/tests/dromaeo/cssquery-prototype.html (next: http://localhost:62702/tests/dromaeo/cssquery-yui.html) 01:01:56 INFO - PROCESS | 2509 | RSS: Main: 338755584 01:01:56 INFO - PROCESS | 2509 | 01:04:59 INFO - PROCESS | 2509 | Cycle 1(1): loaded http://localhost:62702/tests/dromaeo/cssquery-yui.html (next: http://localhost:62702/tests/dromaeo/cssquery-dojo.html) 01:04:59 INFO - PROCESS | 2509 | RSS: Main: 381190144 01:04:59 INFO - PROCESS | 2509 | 01:04:59 INFO - PROCESS | 2509 | __start_tp_report 01:04:59 INFO - PROCESS | 2509 | _x_x_mozilla_page_load 01:04:59 INFO - PROCESS | 2509 | _x_x_mozilla_page_load_details 01:04:59 INFO - PROCESS | 2509 | |i|pagename|runs| 01:04:59 INFO - PROCESS | 2509 | |0;dojo.html;3378.679025492579;3389.6101948275923;3425.3149370125975;3434.8454319555617;3486.930261394772;5713.571482138846;5718.771249150034;5767.711614419272;5801.9129713054335;5804.564657650672;5740.282464691913;5810.738516766745;5821.3887541808135;5822;5831.154482600031;5188.066148093341;5236.450172731847;5246.317978662769;5261.710605916673;5303.920441193385;5307.840764777063;5310;5326.7070311132875;5331.067063263933;5331.893362132774;5203.817866374678;5207.583393328544;5222.086134926372;5225.242339860718;5310.0441920454305;5261.184516399964;5286.154215325568;5309.309789727311;5319.760610772524;5364.195370694388;5116.923246151311;5137.51193636604;5140.460251673595;5232.502912223333;5241.3710354757295;5640.0693885508845;5656.377798442168;5715.371309155989;5716.3426205986425;5727.599068065237;6286.622802631811;6319.210098737658;6322.841928951767;6332.1135041094285;6344.3655634436645;6159.938400616026;6219.87560248797;6240.875182496325;6315.557910946236;6320.367963203643;5842.795502157404;5868.501177399883;5879.617824841372;5919.289685237799;5937.376575459601;6321.747130114836;6324.588901721466;6326.367363263636;6335.239771227482;6337.081123237197;6079.452849243589;6126.884121540732;6252.437280644764;6256.405641464054;6261.686915654199;6276.748930042839;6298.464630506458;6326.620402775849;6333.588316759396;6443.226812782496;6209.410106039919;6263.592866463665;6286.994080947026;6294.937050629435;6302.401271879165;6122.642120310999;6182.28903676074;6206.317305096436;6303.432691057827;6310;4957.975210123998;4992.201247800334;5000.499950004971;5004.874878128003;5007.7245751484015;4412.117576484716;4465.531119232499;4481.462224533077;4489.708168969007;4512;5784.739686714108;5825.271841019872;5835.503982161564;5847.970760146257;5856.531477481792;6267.467265282502;6289.371062893674;6300.905486417708;6308.3060863305;6326.398992095744;5630.634008789416;5649.745761440745;5673.631213971079;5679.971600142056;5754.74103665336;5463.863403414946;5467.863303417446;5473.17902314656;5473.370562385297;5473.589480788957;6245.094461303176;6304.621722696653;6308.179936608211;6418.903716444163;6420.6789660517725;6305.842353941188;6310.432061114522;6321.715522801577;6338.778142764981;6343.9682801585695;5730.885382292247;6087.721578468571;6389.041643753474;6398.4561312287115;6403.3276505966205;6112.625067133324;6142.1304578115505;6203.131561581473;6222.066689996536;6245.156903818108;6255.749770009149;6305.243370795534;6305.5901366411035;6318.273398559225;6330.493560515056;4863.440704319049;6184.845378865384;6185.350538193425;6188.504919606511;6199.380061993765;6013.939860601338;6062.333143354315;6080.270367555921;6099.279518885317;6117.827404800828;4940.505949405031;5951.077582974646;5953;5958.1360702697;5959.046552551571;6083.969580152071;6116.853194527988;6126.969365153146;6136.6011209271255;6137.048757442603;6018.699065046642;6041.667708276087;6044.546659000681;6049.606775559574;6055.182550355646 01:04:59 INFO - PROCESS | 2509 | |1;ext.html;507.55081752648897;530.5887936848942;532.1459058211573;561.6152935239359;576.1904524143579;22806.429839254026;30502.84748576254;31259.843700781494;31260;41246.17507649845;43299;43523.78238108813;43594.91012724675;43649.90875228121;43779.562204377944;9480.762980925463;9655.034496550325;9657.75855603612;9659.613615455373;9676.129148376645;8384.245417912387;8591.140885911422;9419.246460283195;11272.380019098946;11495.827562586568;11364.068108421588;11410.08719302458;11421.885781142208;11432;11433;8450.577471126418;11469.369184694839;11505.539778408853;11517.712057198596;11538.192326537146;10138.681971343678;11062.612808551663;11352.943235283854;11365.374904380256;11509.712257193638;38153.42769858454;39645.40531892024;39647.20705585872;39656;39832.40251396231;3363.5291059251726;3762.811859407046;3802.7338086333966;3869.8839034829;3927.8036098194975;26036.73963260362;26636.60045099325;29572.40855182906;29823.70176298231;29841.25396865074;2621.973780262192;2702.3514356554483;2775.1258353618746;2791.776657867366;2895.0880472651256;491141;491912.5404372991;492017;492165;494169;59335.40664593385;60368.39631603716;60450.093248601304;60525.39474605286;60643.69678151581;63964.04053939194;64024.67987660032;64081;64081.679591602675;64137;16329.673406531803;16684.165791710366;16723.414680486123;16725.080120593484;16754.4135955241;11960.40197990097;12188.81716774249;13002.414891329912;13080.869191307966;13104.934475327562;16194.514164575081;16660.58348541296;16675.91662041706;16815.6636867262;16848.74726879098;6868.347506986828;6870.725170993204;6882.449404047664;6949.548279361826;6958.330000799771;932.3333816321227;1040.1107053469327;1089.553283153903;1091.3942761767296;1091.5251865438402;27279.590806137923;28742.712572874003;28859.85570072136;29304.974325898493;29616.111516654535;1063.3247887591435;1065.4672663668166;1101.8512500812287;1120.8486854274731;1178.5816035307341;13838.169709817443;15077.170755608327;15136.848631513543;15506.457273995356;15566.533004009898;618160;624573;625181;626355;627567;59609.701951489966;59981.40018599758;60101;60121.39878601158;60218.69890650606;1318.8021796730568;1375.5529452927638;1378.7931810228547;1469.713405885842;1494.4321157959905;25980.35049123787;30156.54765178568;30187.849060754557;30666.926657566877;30844;50384;50782.47652570429;51047;51084.48915510946;51095;497766;501277;501283;502427;502452;20977;23358.48169868953;24647.260582181847;24668.38329041788;39013.609863900994;46143.76928115338;46365;46633.76683116562;47298.527014729414;47826;36173.81913090523;37780.43329350007;37991.81004094962;38283;39046.21907561776;36994;37121.628783711814;37356;37943.62056379401;38064.048398790255;36871;37215.441768372955;37518.812405937795;38058;38092.80953595214;31300;33097.17257068593;33395.66604334023;33702.32595348128;33852.83073584616;24090.39774005664;24344.634830477913;24394.390140246636;24907.62638560459;24975.125870594442 01:04:59 INFO - PROCESS | 2509 | |2;jquery.html;397.15802498702743;401.7228112602305;403.61656426394933;408.53223059596763;422.49170461452627;18738.625227495442;20062.097205625745;21201.681974770392;21226.893865530652;21255.574888502222;17666.646667066652;20439.284625038126;20442.693359599616;21195.258165964195;21311.893440532815;5781.82654520365;6072.02847544393;6094.512439004891;6095.603785753912;6115.541334399915;5867.765289388441;5940.554458415614;5959.910601340984;5965.791197308095;6069.514438844882;5749.741261643215;5869.911951320733;5902.675352855591;5903.557233207526;5913.881722365551;5732.570057245703;5855.238818953509;5867.501262392701;5903.49820265278;5919.023361145406;5000.774965126578;5144.43411224765;5164.1995490699;5165.66423182492;5167.302414174113;24303.027878884823;25246.990120395163;25700.8714956424;25770.484590308275;25773.871130644413;1937.728717979484;2169.1323470611883;2256.605094108541;2259.819214462839;2281.931542053741;5729.054705973509;5814.563907706939;10103.747406314827;10217.386956782617;13838.892888568891;1718.3384397007117;1784.50925995351;1818.7362832389363;1870.9345172919018;1879.2952642758964;123858.38070809588;128100.35949820191;129597;131464.342678286;131503.68496315106;11458.19792614509;11576.24754390962;11605.825912611317;11836.467358968866;12084.851939065775;15015;15067.246637668073;15069.321880515185;15109.622259443382;15110.924445377703;8349.749507514784;8589.312854971588;8613.932656162477;8701.956490217508;8761.25529330014;8392.790180245544;8402.831943361098;8406.411551191359;8515.914840851512;8529.445586037013;4030.415589739471;4093.201825643971;4275.508316543575;4314.762688052125;4653.302004699322;1798.7571677823587;1918.9904050479659;1946.3090602836073;1950.648883201009;1952.7461430014007;723.2116992478224;894.7449976756593;911.4166933162782;978.8727465429448;991.0436428846198;17292.913535432242;18640.34758783436;20230.69653955192;20358.491037723878;20388.490287742927;936.765808547863;946.6071580294239;958.5782255807422;965;1006.0112891316114;8877.422967507091;9016.8647470288;9468.384555003902;10676.145908327315;10846.78306433867;589418;591747;595672;602565;602706;126948;131318.68681313255;136708.31645841905;139397;141886.29056854857;977.7408986618552;1005.2259759984771;1055.9155267578576;1057.724991502215;1063.7393838509615;18272;18509.35217267389;18630.068496574844;18834.5815922535;18889;51054.744726276134;51625.741871290404;53109.20336195037;53324.2001369972;53445.7327713359;57047;72392;74898.8765168512;77249.61375193088;80736.59631701803;6690.565113267622;7750.302472777477;8104.635291411782;8209.302209312274;8460.407771456184;9034.954825225832;11767.588134415255;11788.4695188715;12139.635810925687;12287.75424491523;10594.2584019121;10644.733881653021;10978.615748448767;11036.779264414827;11071.50178241965;10317.690469285933;10361.22290828206;10385.376877387382;10702.304350217211;10887.836682449928;10868.021878031015;10873.565057397616;11086.61196858106;11259;11284.887151128383;7940.960295198487;8157.959210203911;8161.877571836309;8230.42387032902;8402.11777763326;9490.762730931781;10780.407077610807;10808;10870.945645271722;11001.504932277907 01:04:59 INFO - PROCESS | 2509 | |3;mootools.html;1421.822272215973;1591.1089789717755;1622.3186261770059;1814.2652225848535;1931.4881556387552;7763.146053934062;8487.15128487151;8724.171203735636;8734.694285699994;8864.379493435463;8447.23974842264;8726.301895848334;8735.86896196556;8739.082396348387;8743.475391476515;2115.9788402116014;2196.923107691231;2351.1535847095033;2394.221877889684;2431.7081950165916;2027.8681885677383;2456.91400800972;2460.065175233409;2461.7661322174454;2466.334089795754;2371.2174982255815;2409.3736727708815;2413.1312727418117;2416.8429052111646;2420.177139772477;2097.92657256996;2303.3205204464557;2348.7886090251873;2380;2381.5594115088766;1989.7413336266338;2410.541997020578;2427.1504973259393;2429.9028038878423;2433.209207007712;8529.10444403335;8601.827963440759;8605.440646357967;8609.009963854174;8615.181557752003;5148.794048238066;5277.762500687479;5297.073012222845;5304.257403963448;5308.628396012281;2784.610154578361;3327.584051993501;3427.708644765173;3446.2590543033366;3450.7756995795444;1041.8020576090594;1044.269011691811;1045.8222102242562;1048.2452634103433;1061.3790932304612;9626.711198664052;11542;11588.594399196072;11608.187426880122;11609.18735688502;4023.396490526386;4612.76936153191;4789.712617242976;4829.6860704054125;4880.487548807395;9736.513174341255;9776.755581110529;9785.951070244746;9786.070323319273;9788;5277.551408130275;5282.075636763551;5313.335833020872;5326.707031113326;5333.493318134771;4346.782660866944;4484.8430304939175;4734.242521196592;4879.829205977774;4906.190478570996;3836.3286424875537;3872.186840763471;3954.4661470701662;3995.9400608990886;4057.857974970862;1315.953941612058;1334.4128583423262;1338.6519504928788;1344.8050032745198;1354.6748780292855;819.4141189049858;896.0815164456484;909.086368199968;948.0330063335359;949.2453499467939;5390.595705322038;5671.971640141773;5764.164196191528;5797.855053623609;5824.912626310609;919.8390281700675;948.1798244518442;958.3579002068631;963.3160456076264;1013.0983424751977;3197.6802319768303;3418.3163367326633;3721.4975978243133;3784.3566593679143;3806.5241844769403;9515.857262141073;9576.616935322649;10693.144548436107;10724.946375268073;10853.837192442119;10274.897251027394;10676.572937082585;10697.41164235959;10780.568777248822;10794.028537431512;7999.920000800034;8163.918360816316;8182.754517364369;8194.754157375288;8278.586070696441;5585.190147428519;5667.886642267049;5716.571257155813;5779.682117483579;5798.072308430631;9882.11061004513;9925.404475731479;10027.699169024649;10030.949845250727;10042.748931276776;9993.850092248767;10032.448215348226;10045.748856278651;10117.443540605338;10265.538050787583;6460.418562329413;6664.66676666155;6668.633225172662;6948.6178260196175;7076.115485564304;7795.922040779519;7878.763637090897;7881.684732610632;7951.880721789062;8218.178182181973;7188.317109874553;7366.263373662591;7415.592142432218;7516.736914207976;7581.3176814087;7022.613756243456;7218.494705370784;7258.818529536593;7294.63526823646;7340.669669864762;6875.346842049998;6968.268331825086;6983.266756990646;6993.2657071006815;7017.333353331425;4522.208613492691;4530.365748795237;4636.907261854676;4709.905801884011;4943.876403089952;4610.677252592286;4771.928421073756;4794.520547945178;4888.706677599355;5030.195168772979 01:04:59 INFO - PROCESS | 2509 | |4;prototype.html;304.77142143392456;316.6311247396781;319.2258772476743;323.6827908649522;326.5933912279213;5045.217991211368;5091.389033316007;5212.1660534314515;5243.764030618622;5295.761690723922;4411.713238639486;4589.862304130873;4814.7351895645725;5267.920981185285;5280.709560974145;3798.8860334190017;3813.2564149990762;3945.5068116485295;3963.9189425551526;3984.5417776955587;3887.9805600971954;3981.8696997106554;3996.8401263949554;3999.7400168988925;4022.275990321741;3600.6219346968583;3606.098475381155;3728.5152930119048;3949.4273330367223;3951.9802400988096;3210.186244771708;3749.531308586427;3778.9244215115546;3936.783476908769;3938.960610393917;2896.8551572421507;3309.1561651778643;3420.6066302375298;3481.477778333245;3505.5793304803333;4348.434703488558;4469.351943968107;4666.919832910263;4998.675086119427;5051.141305977956;3986.1828325193264;4268.679849011337;4478.619317358029;4522.773861306954;4575.76454357325;4030.858919937788;4713.387259656257;5393.622446428752;5432.348118225798;5527.987246950422;3304.735621150302;3824.6557809796973;3831.4252862070634;3853.1523064925673;3905.7070719695853;4319.092990472004;4520.897782531106;4763.880902977418;5562.972185139089;5666.5183459406;3128.515080162578;3400.795952242873;3858.2862170498547;3865.40086286626;3874.643874643852;3232.660570640096;3511.7366197534775;3699.463577781207;3897.279003384383;4045.9190816183514;4082.3264161413035;4155.501339839238;4294.003288470876;4326.567343265711;4569.885752856205;4423.955760442419;5289.814856480081;5416.241726158341;5470.510917532513;5490.090252166358;1145.2956431794387;1247.2828123828654;1286.4275397448046;1363.175278956237;1363.5636596289091;723.9348458638748;765.6784150656738;794.0471434278888;803.7789607857886;843.6920524008815;4317.222899877989;4677.64917631179;4991;5091.523458197082;5196.92204616931;5394.811181608625;5676.091825307931;5743.741531631086;5754.712264386764;5791.58296605005;4739.312799644033;4906.828261010847;5139.922901156486;5156.510131537498;5192.636515443958;2556.8366393291008;2925.3033240720333;2971;2973.3755911258877;2991.1624745071413;5497.752601132958;5645.23789288449;5834.358220595731;5844.503217226498;5986.5210783137245;5011.674241174312;5576.302962129734;5690.77236910519;5764.884702305931;5801.680907550125;4498.515489888395;5181.35233095863;5186;5811.94188058114;5844.649321040752;5273.551748101454;5481.903619276081;5625.5218306442775;5651.4348565143155;5659.547236221175;4710.4347478302825;4813.9300100956925;5223.947760522498;5536.889262214652;5545.473180047889;5177.637565370388;5401.3248343957;5796.652200867961;5806.6806325650805;5903.940960590339;2948.6903875092808;2980.5231163013814;3319.385913605894;3327.5175099609937;3340.7828491148;4074.5942649785775;4819.421669399694;5196.922046169386;5242.816501422432;5681.289838770153;4844.795617993875;5141.511556402136;5433.1035379161995;5539.7230138492105;5597.908407860428;4623.3758442609815;5153.793848246178;5214.97392513035;5532.589189756586;5684.658920464785;4772.785224664968;5196.830713089525;5306.8938621228135;5310.654807437504;5446.782128714807;4834.613230941446;4949.7030178189425;5224.7721785379645;5385.649932754359;5522.723863806713;4680.929786053144;5357.571394288526;5387.821185500574;5419.268398766116;5446 01:04:59 INFO - PROCESS | 2509 | |5;yui.html;631.207834168119;729.1432566734089;735.753522569939;746.2201998911137;754.7999780058288;12277.69305767354;14506.202158881271;14691.632709182275;14939.17834519101;14952.177630230333;14163.150210987347;14383.137011779301;14402.711945761079;14410.855891441082;14417.207053612074;712.9394001509878;729.4419768876813;898.2454738020078;916.3493919317293;919.5723988345418;896.4397251717677;914.5792935249793;917.9862302065475;930.0978051290238;930.9534523273843;965.2712202287263;974.7855471796194;975.3465178330536;976.1897624971257;977.7115750853516;751.7481643649384;765.1392183793232;765.9502132361379;964.662368171138;968.7674958010062;572.9770809167628;575.1947273816648;700.8773464643667;712.1454254894147;714.1537278325201;14576.052556583789;14746.967712260146;14812.851871481254;14824.481143159937;14831.258437078102;12029.639110826602;12035.338056406894;12120.45457954394;12233.143679942319;12345.074119440987;10514.264001519896;10641.73395665107;10685.732856678567;11064.17018723591;11218.102551795837;3905.7265991380614;3937.27160475313;3957.465742124834;3985.143194213258;4114.280000999813;77362.61318693426;77385.61307193484;78365.82451263236;78573.60713196397;78726.60636696836;9148.359614827032;9324.673636422693;9354.144561347903;9362.90637093634;9627.374220675634;16156.353745850269;20837.166513339296;20857.582848342947;20863.791362086184;20897.268595599384;7035.894461583081;7073.823154421078;7124.145102587619;7272.490925635155;7286.562806231643;3708.2583483303447;3851.5378154621626;3918.294706952776;3956.584558621303;3984.00399900025;3342.6824451677053;3452.4648679454726;3454.7908232118557;3457.6715212054814;3462.8787992420143;620.6462316479564;627.2754968011953;629.3077614623877;638.5976834594176;641.1985018726592;518.3054706693049;547.2639300141303;566.2553741829563;570.4694633990429;572.2846441947565;7622.656980435893;7707.421943354271;7809.765707028798;7980.800479988046;8215.548144852091;493.331535769035;514.8018013064997;536.551979097458;563.0681222576642;600.4715850052014;2746.5605503119405;3242.6705050929004;3502.667246611568;3506.333796578642;3903.1998440319667;83761.16238837533;85249.72125418123;86078;87133.56433217794;88278.55860720655;72434.27565724381;75620.62189689017;75700;76165;78844.60577697075;67552.66223668752;67597;68039.31960680429;68205.63588728297;68243;5968.850778730566;7153.713851446092;7220.819479513054;7377.336039756233;7554.924450755642;42179.367309491;42541.7237482876;42830.78584607057;43708.78145609252;43737;44821.77589112164;59963;60329;60395.39604604073;60477;9432.481213533321;9541.761455963657;9722.75693107678;9899.901000990187;10188.976221073419;8793.472391656522;10360.740981475523;10510.001549852752;10606.257561970588;10671.199660025373;9595.28035397334;9595.520223988913;9771.85342219853;10092.798144036931;10145.137663298703;9792.608295668093;9818.214542836413;9982.251331150337;10130.949345253226;10330.328528645614;9817.85273220888;9921.553530091307;9967.40195588267;9968.152707019985;10018.248631352531;8321.292690121407;8509.021462531888;8512.829743405222;8578.356623253405;8720.389572729848;8299.6680132794;8441.535715535705;8495.895533580595;8564.057953624972;8876.423032502867 01:04:59 INFO - PROCESS | 2509 | __end_tp_report 01:04:59 INFO - PROCESS | 2509 | __start_cc_report 01:04:59 INFO - PROCESS | 2509 | _x_x_mozilla_cycle_collect,2164 01:04:59 INFO - PROCESS | 2509 | __end_cc_report 01:04:59 INFO - PROCESS | 2509 | __startTimestamp1463040299631__endTimestamp 01:04:59 INFO - PROCESS | 2509 | 01:04:59 INFO - PROCESS | 2509 | ------- Summary: start ------- 01:04:59 INFO - PROCESS | 2509 | Number of tests: 6 01:04:59 INFO - PROCESS | 2509 | 01:04:59 INFO - PROCESS | 2509 | [#0] dojo.html Cycles:165 Average:5781.58 Median:6013.94 stddev:641.18 (10.7%) stddev-sans-first:614.82 01:04:59 INFO - PROCESS | 2509 | Values: 3378.7 3389.6 3425.3 3434.8 3486.9 5713.6 5718.8 5767.7 5801.9 5804.6 5740.3 5810.7 5821.4 5822.0 5831.2 5188.1 5236.5 5246.3 5261.7 5303.9 5307.8 5310.0 5326.7 5331.1 5331.9 5203.8 5207.6 5222.1 5225.2 5310.0 5261.2 5286.2 5309.3 5319.8 5364.2 5116.9 5137.5 5140.5 5232.5 5241.4 5640.1 5656.4 5715.4 5716.3 5727.6 6286.6 6319.2 6322.8 6332.1 6344.4 6159.9 6219.9 6240.9 6315.6 6320.4 5842.8 5868.5 5879.6 5919.3 5937.4 6321.7 6324.6 6326.4 6335.2 6337.1 6079.5 6126.9 6252.4 6256.4 6261.7 6276.7 6298.5 6326.6 6333.6 6443.2 6209.4 6263.6 6287.0 6294.9 6302.4 6122.6 6182.3 6206.3 6303.4 6310.0 4958.0 4992.2 5000.5 5004.9 5007.7 4412.1 4465.5 4481.5 4489.7 4512.0 5784.7 5825.3 5835.5 5848.0 5856.5 6267.5 6289.4 6300.9 6308.3 6326.4 5630.6 5649.7 5673.6 5680.0 5754.7 5463.9 5467.9 5473.2 5473.4 5473.6 6245.1 6304.6 6308.2 6418.9 6420.7 6305.8 6310.4 6321.7 6338.8 6344.0 5730.9 6087.7 6389.0 6398.5 6403.3 6112.6 6142.1 6203.1 6222.1 6245.2 6255.7 6305.2 6305.6 6318.3 6330.5 4863.4 6184.8 6185.4 6188.5 6199.4 6013.9 6062.3 6080.3 6099.3 6117.8 4940.5 5951.1 5953.0 5958.1 5959.0 6084.0 6116.9 6127.0 6136.6 6137.0 6018.7 6041.7 6044.5 6049.6 6055.2 01:04:59 INFO - PROCESS | 2509 | 01:04:59 INFO - PROCESS | 2509 | [#1] ext.html Cycles:180 Average:67478.29 Median:36932.50 stddev:144806.66 (392.1%) stddev-sans-first:145125.58 01:04:59 INFO - PROCESS | 2509 | Values: 507.6 530.6 532.1 561.6 576.2 22806.4 30502.8 31259.8 31260.0 41246.2 43299.0 43523.8 43594.9 43649.9 43779.6 9480.8 9655.0 9657.8 9659.6 9676.1 8384.2 8591.1 9419.2 11272.4 11495.8 11364.1 11410.1 11421.9 11432.0 11433.0 8450.6 11469.4 11505.5 11517.7 11538.2 10138.7 11062.6 11352.9 11365.4 11509.7 38153.4 39645.4 39647.2 39656.0 39832.4 3363.5 3762.8 3802.7 3869.9 3927.8 26036.7 26636.6 29572.4 29823.7 29841.3 2622.0 2702.4 2775.1 2791.8 2895.1 491141.0 491912.5 492017.0 492165.0 494169.0 59335.4 60368.4 60450.1 60525.4 60643.7 63964.0 64024.7 64081.0 64081.7 64137.0 16329.7 16684.2 16723.4 16725.1 16754.4 11960.4 12188.8 13002.4 13080.9 13104.9 16194.5 16660.6 16675.9 16815.7 16848.7 6868.3 6870.7 6882.4 6949.5 6958.3 932.3 1040.1 1089.6 1091.4 1091.5 27279.6 28742.7 28859.9 29305.0 29616.1 1063.3 1065.5 1101.9 1120.8 1178.6 13838.2 15077.2 15136.8 15506.5 15566.5 618160.0 624573.0 625181.0 626355.0 627567.0 59609.7 59981.4 60101.0 60121.4 60218.7 1318.8 1375.6 1378.8 1469.7 1494.4 25980.4 30156.5 30187.8 30666.9 30844.0 50384.0 50782.5 51047.0 51084.5 51095.0 497766.0 501277.0 501283.0 502427.0 502452.0 20977.0 23358.5 24647.3 24668.4 39013.6 46143.8 46365.0 46633.8 47298.5 47826.0 36173.8 37780.4 37991.8 38283.0 39046.2 36994.0 37121.6 37356.0 37943.6 38064.0 36871.0 37215.4 37518.8 38058.0 38092.8 31300.0 33097.2 33395.7 33702.3 33852.8 24090.4 24344.6 24394.4 24907.6 24975.1 01:04:59 INFO - PROCESS | 2509 | 01:04:59 INFO - PROCESS | 2509 | [#2] jquery.html Cycles:180 Average:35144.52 Median:25735.68 stddev:100018.10 (388.6%) stddev-sans-first:100264.65 01:04:59 INFO - PROCESS | 2509 | Values: 397.2 401.7 403.6 408.5 422.5 18738.6 20062.1 21201.7 21226.9 21255.6 17666.6 20439.3 20442.7 21195.3 21311.9 5781.8 6072.0 6094.5 6095.6 6115.5 5867.8 5940.6 5959.9 5965.8 6069.5 5749.7 5869.9 5902.7 5903.6 5913.9 5732.6 5855.2 5867.5 5903.5 5919.0 5000.8 5144.4 5164.2 5165.7 5167.3 24303.0 25247.0 25700.9 25770.5 25773.9 1937.7 2169.1 2256.6 2259.8 2281.9 5729.1 5814.6 10103.7 10217.4 13838.9 1718.3 1784.5 1818.7 1870.9 1879.3 123858.4 128100.4 129597.0 131464.3 131503.7 11458.2 11576.2 11605.8 11836.5 12084.9 15015.0 15067.2 15069.3 15109.6 15110.9 8349.7 8589.3 8613.9 8702.0 8761.3 8392.8 8402.8 8406.4 8515.9 8529.4 4030.4 4093.2 4275.5 4314.8 4653.3 1798.8 1919.0 1946.3 1950.6 1952.7 723.2 894.7 911.4 978.9 991.0 17292.9 18640.3 20230.7 20358.5 20388.5 936.8 946.6 958.6 965.0 1006.0 8877.4 9016.9 9468.4 10676.1 10846.8 589418.0 591747.0 595672.0 602565.0 602706.0 126948.0 131318.7 136708.3 139397.0 141886.3 977.7 1005.2 1055.9 1057.7 1063.7 18272.0 18509.4 18630.1 18834.6 18889.0 51054.7 51625.7 53109.2 53324.2 53445.7 57047.0 72392.0 74898.9 77249.6 80736.6 6690.6 7750.3 8104.6 8209.3 8460.4 9035.0 11767.6 11788.5 12139.6 12287.8 10594.3 10644.7 10978.6 11036.8 11071.5 10317.7 10361.2 10385.4 10702.3 10887.8 10868.0 10873.6 11086.6 11259.0 11284.9 7941.0 8158.0 8161.9 8230.4 8402.1 9490.8 10780.4 10808.0 10870.9 11001.5 01:04:59 INFO - PROCESS | 2509 | 01:04:59 INFO - PROCESS | 2509 | [#3] mootools.html Cycles:180 Average:5595.77 Median:4884.60 stddev:3135.02 (64.2%) stddev-sans-first:3128.12 01:04:59 INFO - PROCESS | 2509 | Values: 1421.8 1591.1 1622.3 1814.3 1931.5 7763.1 8487.2 8724.2 8734.7 8864.4 8447.2 8726.3 8735.9 8739.1 8743.5 2116.0 2196.9 2351.2 2394.2 2431.7 2027.9 2456.9 2460.1 2461.8 2466.3 2371.2 2409.4 2413.1 2416.8 2420.2 2097.9 2303.3 2348.8 2380.0 2381.6 1989.7 2410.5 2427.2 2429.9 2433.2 8529.1 8601.8 8605.4 8609.0 8615.2 5148.8 5277.8 5297.1 5304.3 5308.6 2784.6 3327.6 3427.7 3446.3 3450.8 1041.8 1044.3 1045.8 1048.2 1061.4 9626.7 11542.0 11588.6 11608.2 11609.2 4023.4 4612.8 4789.7 4829.7 4880.5 9736.5 9776.8 9786.0 9786.1 9788.0 5277.6 5282.1 5313.3 5326.7 5333.5 4346.8 4484.8 4734.2 4879.8 4906.2 3836.3 3872.2 3954.5 3995.9 4057.9 1316.0 1334.4 1338.7 1344.8 1354.7 819.4 896.1 909.1 948.0 949.2 5390.6 5672.0 5764.2 5797.9 5824.9 919.8 948.2 958.4 963.3 1013.1 3197.7 3418.3 3721.5 3784.4 3806.5 9515.9 9576.6 10693.1 10724.9 10853.8 10274.9 10676.6 10697.4 10780.6 10794.0 7999.9 8163.9 8182.8 8194.8 8278.6 5585.2 5667.9 5716.6 5779.7 5798.1 9882.1 9925.4 10027.7 10030.9 10042.7 9993.9 10032.4 10045.7 10117.4 10265.5 6460.4 6664.7 6668.6 6948.6 7076.1 7795.9 7878.8 7881.7 7951.9 8218.2 7188.3 7366.3 7415.6 7516.7 7581.3 7022.6 7218.5 7258.8 7294.6 7340.7 6875.3 6968.3 6983.3 6993.3 7017.3 4522.2 4530.4 4636.9 4709.9 4943.9 4610.7 4771.9 4794.5 4888.7 5030.2 01:04:59 INFO - PROCESS | 2509 | 01:04:59 INFO - PROCESS | 2509 | [#4] prototype.html Cycles:180 Average:4347.81 Median:4814.33 stddev:1357.11 (28.2%) stddev-sans-first:1326.55 01:04:59 INFO - PROCESS | 2509 | Values: 304.8 316.6 319.2 323.7 326.6 5045.2 5091.4 5212.2 5243.8 5295.8 4411.7 4589.9 4814.7 5267.9 5280.7 3798.9 3813.3 3945.5 3963.9 3984.5 3888.0 3981.9 3996.8 3999.7 4022.3 3600.6 3606.1 3728.5 3949.4 3952.0 3210.2 3749.5 3778.9 3936.8 3939.0 2896.9 3309.2 3420.6 3481.5 3505.6 4348.4 4469.4 4666.9 4998.7 5051.1 3986.2 4268.7 4478.6 4522.8 4575.8 4030.9 4713.4 5393.6 5432.3 5528.0 3304.7 3824.7 3831.4 3853.2 3905.7 4319.1 4520.9 4763.9 5563.0 5666.5 3128.5 3400.8 3858.3 3865.4 3874.6 3232.7 3511.7 3699.5 3897.3 4045.9 4082.3 4155.5 4294.0 4326.6 4569.9 4424.0 5289.8 5416.2 5470.5 5490.1 1145.3 1247.3 1286.4 1363.2 1363.6 723.9 765.7 794.0 803.8 843.7 4317.2 4677.6 4991.0 5091.5 5196.9 5394.8 5676.1 5743.7 5754.7 5791.6 4739.3 4906.8 5139.9 5156.5 5192.6 2556.8 2925.3 2971.0 2973.4 2991.2 5497.8 5645.2 5834.4 5844.5 5986.5 5011.7 5576.3 5690.8 5764.9 5801.7 4498.5 5181.4 5186.0 5811.9 5844.6 5273.6 5481.9 5625.5 5651.4 5659.5 4710.4 4813.9 5223.9 5536.9 5545.5 5177.6 5401.3 5796.7 5806.7 5903.9 2948.7 2980.5 3319.4 3327.5 3340.8 4074.6 4819.4 5196.9 5242.8 5681.3 4844.8 5141.5 5433.1 5539.7 5597.9 4623.4 5153.8 5215.0 5532.6 5684.7 4772.8 5196.8 5306.9 5310.7 5446.8 4834.6 4949.7 5224.8 5385.6 5522.7 4680.9 5357.6 5387.8 5419.3 5446.0 01:04:59 INFO - PROCESS | 2509 | 01:04:59 INFO - PROCESS | 2509 | [#5] yui.html Cycles:180 Average:17053.94 Median:67818.16 stddev:24106.69 (35.5%) stddev-sans-first:24142.78 01:04:59 INFO - PROCESS | 2509 | Values: 631.2 729.1 735.8 746.2 754.8 12277.7 14506.2 14691.6 14939.2 14952.2 14163.2 14383.1 14402.7 14410.9 14417.2 712.9 729.4 898.2 916.3 919.6 896.4 914.6 918.0 930.1 931.0 965.3 974.8 975.3 976.2 977.7 751.7 765.1 766.0 964.7 968.8 573.0 575.2 700.9 712.1 714.2 14576.1 14747.0 14812.9 14824.5 14831.3 12029.6 12035.3 12120.5 12233.1 12345.1 10514.3 10641.7 10685.7 11064.2 11218.1 3905.7 3937.3 3957.5 3985.1 4114.3 77362.6 77385.6 78365.8 78573.6 78726.6 9148.4 9324.7 9354.1 9362.9 9627.4 16156.4 20837.2 20857.6 20863.8 20897.3 7035.9 7073.8 7124.1 7272.5 7286.6 3708.3 3851.5 3918.3 3956.6 3984.0 3342.7 3452.5 3454.8 3457.7 3462.9 620.6 627.3 629.3 638.6 641.2 518.3 547.3 566.3 570.5 572.3 7622.7 7707.4 7809.8 7980.8 8215.5 493.3 514.8 536.6 563.1 600.5 2746.6 3242.7 3502.7 3506.3 3903.2 83761.2 85249.7 86078.0 87133.6 88278.6 72434.3 75620.6 75700.0 76165.0 78844.6 67552.7 67597.0 68039.3 68205.6 68243.0 5968.9 7153.7 7220.8 7377.3 7554.9 42179.4 42541.7 42830.8 43708.8 43737.0 44821.8 59963.0 60329.0 60395.4 60477.0 9432.5 9541.8 9722.8 9899.9 10189.0 8793.5 10360.7 10510.0 10606.3 10671.2 9595.3 9595.5 9771.9 10092.8 10145.1 9792.6 9818.2 9982.3 10130.9 10330.3 9817.9 9921.6 9967.4 9968.2 10018.2 8321.3 8509.0 8512.8 8578.4 8720.4 8299.7 8441.5 8495.9 8564.1 8876.4 01:04:59 INFO - PROCESS | 2509 | -------- Summary: end -------- 01:04:59 INFO - PROCESS | 2509 | 01:05:00 INFO - TEST-INFO | 2509: exit 0 01:05:00 INFO - TEST-OK | dromaeo_css | took 1117684ms 01:05:00 INFO - TEST-START | kraken 01:05:00 INFO - operating with platform_type : mac_ 01:05:00 INFO - Initialising browser for kraken test... 01:05:00 INFO - TEST-INFO | started process 2512 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmpgMziOq/profile http://localhost:62702/getInfo.html) 01:05:02 INFO - PROCESS | 2512 | __metrics Screen width/height:1600/1200 01:05:02 INFO - PROCESS | 2512 | colorDepth:24 01:05:02 INFO - PROCESS | 2512 | Browser inner width/height: 1024/689 01:05:02 INFO - PROCESS | 2512 | __metrics 01:05:02 INFO - PROCESS | 2512 | 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:05:02 ERROR - PROCESS | 2512 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:05:03 INFO - TEST-INFO | 2512: exit 0 01:05:03 INFO - Browser initialized. 01:05:03 INFO - Running cycle 1/1 for kraken test... 01:05:03 INFO - TEST-INFO | started process 2514 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmpgMziOq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:05:15 INFO - PROCESS | 2514 | RSS: Main: 239271936 01:05:15 INFO - PROCESS | 2514 | 01:05:18 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:62702/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:62702/tests/kraken/driver.html?testName=audio-beat-detection) 01:05:19 INFO - PROCESS | 2514 | RSS: Main: 287076352 01:05:19 INFO - PROCESS | 2514 | 01:05:21 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:62702/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:62702/tests/kraken/driver.html?testName=audio-dft) 01:05:22 INFO - PROCESS | 2514 | RSS: Main: 298975232 01:05:22 INFO - PROCESS | 2514 | 01:05:26 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:62702/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:62702/tests/kraken/driver.html?testName=audio-fft) 01:05:26 INFO - PROCESS | 2514 | RSS: Main: 387518464 01:05:26 INFO - PROCESS | 2514 | 01:05:28 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:62702/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:62702/tests/kraken/driver.html?testName=audio-oscillator) 01:05:29 INFO - PROCESS | 2514 | RSS: Main: 286179328 01:05:29 INFO - PROCESS | 2514 | 01:05:31 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:62702/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:62702/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:05:32 INFO - PROCESS | 2514 | RSS: Main: 289697792 01:05:32 INFO - PROCESS | 2514 | 01:05:35 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:62702/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:62702/tests/kraken/driver.html?testName=imaging-darkroom) 01:05:35 INFO - PROCESS | 2514 | RSS: Main: 325877760 01:05:35 INFO - PROCESS | 2514 | 01:05:38 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:62702/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:62702/tests/kraken/driver.html?testName=imaging-desaturate) 01:05:39 INFO - PROCESS | 2514 | RSS: Main: 328794112 01:05:39 INFO - PROCESS | 2514 | 01:05:42 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:62702/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:62702/tests/kraken/driver.html?testName=json-parse-financial) 01:05:43 INFO - PROCESS | 2514 | RSS: Main: 328638464 01:05:43 INFO - PROCESS | 2514 | 01:05:44 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:62702/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:62702/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:05:45 INFO - PROCESS | 2514 | RSS: Main: 316829696 01:05:45 INFO - PROCESS | 2514 | 01:05:47 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:62702/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:62702/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:05:48 INFO - PROCESS | 2514 | RSS: Main: 324501504 01:05:48 INFO - PROCESS | 2514 | 01:05:50 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:62702/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:62702/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:05:50 INFO - PROCESS | 2514 | RSS: Main: 325234688 01:05:50 INFO - PROCESS | 2514 | 01:05:54 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:62702/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:62702/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:05:54 INFO - PROCESS | 2514 | RSS: Main: 330031104 01:05:54 INFO - PROCESS | 2514 | 01:05:57 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:62702/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:62702/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:05:58 INFO - PROCESS | 2514 | RSS: Main: 327667712 01:05:58 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:62702/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:62702/tests/kraken/driver.html?testName=ai-astar) 01:06:00 INFO - PROCESS | 2514 | RSS: Main: 374231040 01:06:00 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | __start_tp_report 01:06:00 INFO - PROCESS | 2514 | _x_x_mozilla_page_load 01:06:00 INFO - PROCESS | 2514 | _x_x_mozilla_page_load_details 01:06:00 INFO - PROCESS | 2514 | |i|pagename|runs| 01:06:00 INFO - PROCESS | 2514 | |0;ai-astar;113;106;112;114;113;113;112;112;112;111 01:06:00 INFO - PROCESS | 2514 | |1;audio-beat-detection;158;119;143;214;142;143;141;119;174;116 01:06:00 INFO - PROCESS | 2514 | |2;audio-dft;187;185;187;187;185;185;186;182;184;187 01:06:00 INFO - PROCESS | 2514 | |3;audio-fft;78;71;58;127;58;59;58;60;113;56 01:06:00 INFO - PROCESS | 2514 | |4;audio-oscillator;92;102;135;80;80;77;104;80;84;103 01:06:00 INFO - PROCESS | 2514 | |5;imaging-gaussian-blur;87;83;86;83;85;83;86;83;86;83 01:06:00 INFO - PROCESS | 2514 | |6;imaging-darkroom;91;92;92;93;92;92;92;92;92;92 01:06:00 INFO - PROCESS | 2514 | |7;imaging-desaturate;84;84;86;84;84;83;79;84;87;86 01:06:00 INFO - PROCESS | 2514 | |8;json-parse-financial;59;59;59;59;59;57;59;122;60;58 01:06:00 INFO - PROCESS | 2514 | |9;json-stringify-tinderbox;62;66;76;77;72;70;72;67;68;70 01:06:00 INFO - PROCESS | 2514 | |10;stanford-crypto-aes;86;86;86;85;84;86;86;84;85;86 01:06:00 INFO - PROCESS | 2514 | |11;stanford-crypto-ccm;139;152;125;124;142;159;147;149;236;146 01:06:00 INFO - PROCESS | 2514 | |12;stanford-crypto-pbkdf2;171;172;170;169;171;170;170;169;169;165 01:06:00 INFO - PROCESS | 2514 | |13;stanford-crypto-sha256-iterative;66;68;67;67;69;68;70;78;87;87 01:06:00 INFO - PROCESS | 2514 | __end_tp_report 01:06:00 INFO - PROCESS | 2514 | __start_cc_report 01:06:00 INFO - PROCESS | 2514 | _x_x_mozilla_cycle_collect,2229 01:06:00 INFO - PROCESS | 2514 | __end_cc_report 01:06:00 INFO - PROCESS | 2514 | __startTimestamp1463040360361__endTimestamp 01:06:00 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | ------- Summary: start ------- 01:06:00 INFO - PROCESS | 2514 | Number of tests: 14 01:06:00 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.50 stddev:2.20 (2.0%) stddev-sans-first:2.29 01:06:00 INFO - PROCESS | 2514 | Values: 113.0 106.0 112.0 114.0 113.0 113.0 112.0 112.0 112.0 111.0 01:06:00 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | [#1] audio-beat-detection Cycles:10 Average:146.90 Median:143.00 stddev:29.74 (20.8%) stddev-sans-first:31.27 01:06:00 INFO - PROCESS | 2514 | Values: 158.0 119.0 143.0 214.0 142.0 143.0 141.0 119.0 174.0 116.0 01:06:00 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | [#2] audio-dft Cycles:10 Average:185.50 Median:186.50 stddev:1.65 (0.9%) stddev-sans-first:1.66 01:06:00 INFO - PROCESS | 2514 | Values: 187.0 185.0 187.0 187.0 185.0 185.0 186.0 182.0 184.0 187.0 01:06:00 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | [#3] audio-fft Cycles:10 Average:73.80 Median:58.50 stddev:25.53 (43.6%) stddev-sans-first:27.04 01:06:00 INFO - PROCESS | 2514 | Values: 78.0 71.0 58.0 127.0 58.0 59.0 58.0 60.0 113.0 56.0 01:06:00 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:80.00 stddev:17.97 (22.5%) stddev-sans-first:19.05 01:06:00 INFO - PROCESS | 2514 | Values: 92.0 102.0 135.0 80.0 80.0 77.0 104.0 80.0 84.0 103.0 01:06:00 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | [#5] imaging-gaussian-blur Cycles:10 Average:84.50 Median:85.50 stddev:1.65 (1.9%) stddev-sans-first:1.48 01:06:00 INFO - PROCESS | 2514 | Values: 87.0 83.0 86.0 83.0 85.0 83.0 86.0 83.0 86.0 83.0 01:06:00 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | [#6] imaging-darkroom Cycles:10 Average:92.00 Median:92.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 01:06:00 INFO - PROCESS | 2514 | Values: 91.0 92.0 92.0 93.0 92.0 92.0 92.0 92.0 92.0 92.0 01:06:00 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.00 stddev:2.18 (2.6%) stddev-sans-first:2.32 01:06:00 INFO - PROCESS | 2514 | Values: 84.0 84.0 86.0 84.0 84.0 83.0 79.0 84.0 87.0 86.0 01:06:00 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:59.00 stddev:20.01 (33.9%) stddev-sans-first:21.10 01:06:00 INFO - PROCESS | 2514 | Values: 59.0 59.0 59.0 59.0 59.0 57.0 59.0 122.0 60.0 58.0 01:06:00 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:71.00 stddev:4.55 (6.4%) stddev-sans-first:3.79 01:06:00 INFO - PROCESS | 2514 | Values: 62.0 66.0 76.0 77.0 72.0 70.0 72.0 67.0 68.0 70.0 01:06:00 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:86.00 stddev:0.84 (1.0%) stddev-sans-first:0.87 01:06:00 INFO - PROCESS | 2514 | Values: 86.0 86.0 86.0 85.0 84.0 86.0 86.0 84.0 85.0 86.0 01:06:00 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | [#11] stanford-crypto-ccm Cycles:10 Average:151.90 Median:148.00 stddev:31.55 (21.3%) stddev-sans-first:33.11 01:06:00 INFO - PROCESS | 2514 | Values: 139.0 152.0 125.0 124.0 142.0 159.0 147.0 149.0 236.0 146.0 01:06:00 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.60 Median:170.00 stddev:1.90 (1.1%) stddev-sans-first:1.94 01:06:00 INFO - PROCESS | 2514 | Values: 171.0 172.0 170.0 169.0 171.0 170.0 170.0 169.0 169.0 165.0 01:06:00 INFO - PROCESS | 2514 | 01:06:00 INFO - PROCESS | 2514 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.70 Median:69.50 stddev:8.25 (11.9%) stddev-sans-first:8.38 01:06:00 INFO - PROCESS | 2514 | Values: 66.0 68.0 67.0 67.0 69.0 68.0 70.0 78.0 87.0 87.0 01:06:00 INFO - PROCESS | 2514 | -------- Summary: end -------- 01:06:00 INFO - PROCESS | 2514 | 01:06:01 INFO - TEST-INFO | 2514: exit 0 01:06:01 INFO - TEST-OK | kraken | took 61125ms 01:06:01 INFO - SUITE-END | took 1178s 01:06:01 INFO - Completed test suite (00:19:38) 01:06:01 INFO - javascript benchmark 01:06:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3378.679025492579, 3389.6101948275923, 3425.3149370125975, 3434.8454319555617, 3486.930261394772, 5713.571482138846, 5718.771249150034, 5767.711614419272, 5801.9129713054335, 5804.564657650672, 5740.282464691913, 5810.738516766745, 5821.3887541808135, 5822.0, 5831.154482600031, 5188.066148093341, 5236.450172731847, 5246.317978662769, 5261.710605916673, 5303.920441193385, 5307.840764777063, 5310.0, 5326.7070311132875, 5331.067063263933, 5331.893362132774, 5203.817866374678, 5207.583393328544, 5222.086134926372, 5225.242339860718, 5310.0441920454305, 5261.184516399964, 5286.154215325568, 5309.309789727311, 5319.760610772524, 5364.195370694388, 5116.923246151311, 5137.51193636604, 5140.460251673595, 5232.502912223333, 5241.3710354757295, 5640.0693885508845, 5656.377798442168, 5715.371309155989, 5716.3426205986425, 5727.599068065237, 6286.622802631811, 6319.210098737658, 6322.841928951767, 6332.1135041094285, 6344.3655634436645, 6159.938400616026, 6219.87560248797, 6240.875182496325, 6315.557910946236, 6320.367963203643, 5842.795502157404, 5868.501177399883, 5879.617824841372, 5919.289685237799, 5937.376575459601, 6321.747130114836, 6324.588901721466, 6326.367363263636, 6335.239771227482, 6337.081123237197, 6079.452849243589, 6126.884121540732, 6252.437280644764, 6256.405641464054, 6261.686915654199, 6276.748930042839, 6298.464630506458, 6326.620402775849, 6333.588316759396, 6443.226812782496, 6209.410106039919, 6263.592866463665, 6286.994080947026, 6294.937050629435, 6302.401271879165, 6122.642120310999, 6182.28903676074, 6206.317305096436, 6303.432691057827, 6310.0, 4957.975210123998, 4992.201247800334, 5000.499950004971, 5004.874878128003, 5007.7245751484015, 4412.117576484716, 4465.531119232499, 4481.462224533077, 4489.708168969007, 4512.0, 5784.739686714108, 5825.271841019872, 5835.503982161564, 5847.970760146257, 5856.531477481792, 6267.467265282502, 6289.371062893674, 6300.905486417708, 6308.3060863305, 6326.398992095744, 5630.634008789416, 5649.745761440745, 5673.631213971079, 5679.971600142056, 5754.74103665336, 5463.863403414946, 5467.863303417446, 5473.17902314656, 5473.370562385297, 5473.589480788957, 6245.094461303176, 6304.621722696653, 6308.179936608211, 6418.903716444163, 6420.6789660517725, 6305.842353941188, 6310.432061114522, 6321.715522801577, 6338.778142764981, 6343.9682801585695, 5730.885382292247, 6087.721578468571, 6389.041643753474, 6398.4561312287115, 6403.3276505966205, 6112.625067133324, 6142.1304578115505, 6203.131561581473, 6222.066689996536, 6245.156903818108, 6255.749770009149, 6305.243370795534, 6305.5901366411035, 6318.273398559225, 6330.493560515056, 4863.440704319049, 6184.845378865384, 6185.350538193425, 6188.504919606511, 6199.380061993765, 6013.939860601338, 6062.333143354315, 6080.270367555921, 6099.279518885317, 6117.827404800828, 4940.505949405031, 5951.077582974646, 5953.0, 5958.1360702697, 5959.046552551571, 6083.969580152071, 6116.853194527988, 6126.969365153146, 6136.6011209271255, 6137.048757442603, 6018.699065046642, 6041.667708276087, 6044.546659000681, 6049.606775559574, 6055.182550355646], "value": 5741.333553317731, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.55081752648897, 530.5887936848942, 532.1459058211573, 561.6152935239359, 576.1904524143579, 22806.429839254026, 30502.84748576254, 31259.843700781494, 31260.0, 41246.17507649845, 43299.0, 43523.78238108813, 43594.91012724675, 43649.90875228121, 43779.562204377944, 9480.762980925463, 9655.034496550325, 9657.75855603612, 9659.613615455373, 9676.129148376645, 8384.245417912387, 8591.140885911422, 9419.246460283195, 11272.380019098946, 11495.827562586568, 11364.068108421588, 11410.08719302458, 11421.885781142208, 11432.0, 11433.0, 8450.577471126418, 11469.369184694839, 11505.539778408853, 11517.712057198596, 11538.192326537146, 10138.681971343678, 11062.612808551663, 11352.943235283854, 11365.374904380256, 11509.712257193638, 38153.42769858454, 39645.40531892024, 39647.20705585872, 39656.0, 39832.40251396231, 3363.5291059251726, 3762.811859407046, 3802.7338086333966, 3869.8839034829, 3927.8036098194975, 26036.73963260362, 26636.60045099325, 29572.40855182906, 29823.70176298231, 29841.25396865074, 2621.973780262192, 2702.3514356554483, 2775.1258353618746, 2791.776657867366, 2895.0880472651256, 491141.0, 491912.5404372991, 492017.0, 492165.0, 494169.0, 59335.40664593385, 60368.39631603716, 60450.093248601304, 60525.39474605286, 60643.69678151581, 63964.04053939194, 64024.67987660032, 64081.0, 64081.679591602675, 64137.0, 16329.673406531803, 16684.165791710366, 16723.414680486123, 16725.080120593484, 16754.4135955241, 11960.40197990097, 12188.81716774249, 13002.414891329912, 13080.869191307966, 13104.934475327562, 16194.514164575081, 16660.58348541296, 16675.91662041706, 16815.6636867262, 16848.74726879098, 6868.347506986828, 6870.725170993204, 6882.449404047664, 6949.548279361826, 6958.330000799771, 932.3333816321227, 1040.1107053469327, 1089.553283153903, 1091.3942761767296, 1091.5251865438402, 27279.590806137923, 28742.712572874003, 28859.85570072136, 29304.974325898493, 29616.111516654535, 1063.3247887591435, 1065.4672663668166, 1101.8512500812287, 1120.8486854274731, 1178.5816035307341, 13838.169709817443, 15077.170755608327, 15136.848631513543, 15506.457273995356, 15566.533004009898, 618160.0, 624573.0, 625181.0, 626355.0, 627567.0, 59609.701951489966, 59981.40018599758, 60101.0, 60121.39878601158, 60218.69890650606, 1318.8021796730568, 1375.5529452927638, 1378.7931810228547, 1469.713405885842, 1494.4321157959905, 25980.35049123787, 30156.54765178568, 30187.849060754557, 30666.926657566877, 30844.0, 50384.0, 50782.47652570429, 51047.0, 51084.48915510946, 51095.0, 497766.0, 501277.0, 501283.0, 502427.0, 502452.0, 20977.0, 23358.48169868953, 24647.260582181847, 24668.38329041788, 39013.609863900994, 46143.76928115338, 46365.0, 46633.76683116562, 47298.527014729414, 47826.0, 36173.81913090523, 37780.43329350007, 37991.81004094962, 38283.0, 39046.21907561776, 36994.0, 37121.628783711814, 37356.0, 37943.62056379401, 38064.048398790255, 36871.0, 37215.441768372955, 37518.812405937795, 38058.0, 38092.80953595214, 31300.0, 33097.17257068593, 33395.66604334023, 33702.32595348128, 33852.83073584616, 24090.39774005664, 24344.634830477913, 24394.390140246636, 24907.62638560459, 24975.125870594442], "value": 19880.09069615571, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.15802498702743, 401.7228112602305, 403.61656426394933, 408.53223059596763, 422.49170461452627, 18738.625227495442, 20062.097205625745, 21201.681974770392, 21226.893865530652, 21255.574888502222, 17666.646667066652, 20439.284625038126, 20442.693359599616, 21195.258165964195, 21311.893440532815, 5781.82654520365, 6072.02847544393, 6094.512439004891, 6095.603785753912, 6115.541334399915, 5867.765289388441, 5940.554458415614, 5959.910601340984, 5965.791197308095, 6069.514438844882, 5749.741261643215, 5869.911951320733, 5902.675352855591, 5903.557233207526, 5913.881722365551, 5732.570057245703, 5855.238818953509, 5867.501262392701, 5903.49820265278, 5919.023361145406, 5000.774965126578, 5144.43411224765, 5164.1995490699, 5165.66423182492, 5167.302414174113, 24303.027878884823, 25246.990120395163, 25700.8714956424, 25770.484590308275, 25773.871130644413, 1937.728717979484, 2169.1323470611883, 2256.605094108541, 2259.819214462839, 2281.931542053741, 5729.054705973509, 5814.563907706939, 10103.747406314827, 10217.386956782617, 13838.892888568891, 1718.3384397007117, 1784.50925995351, 1818.7362832389363, 1870.9345172919018, 1879.2952642758964, 123858.38070809588, 128100.35949820191, 129597.0, 131464.342678286, 131503.68496315106, 11458.19792614509, 11576.24754390962, 11605.825912611317, 11836.467358968866, 12084.851939065775, 15015.0, 15067.246637668073, 15069.321880515185, 15109.622259443382, 15110.924445377703, 8349.749507514784, 8589.312854971588, 8613.932656162477, 8701.956490217508, 8761.25529330014, 8392.790180245544, 8402.831943361098, 8406.411551191359, 8515.914840851512, 8529.445586037013, 4030.415589739471, 4093.201825643971, 4275.508316543575, 4314.762688052125, 4653.302004699322, 1798.7571677823587, 1918.9904050479659, 1946.3090602836073, 1950.648883201009, 1952.7461430014007, 723.2116992478224, 894.7449976756593, 911.4166933162782, 978.8727465429448, 991.0436428846198, 17292.913535432242, 18640.34758783436, 20230.69653955192, 20358.491037723878, 20388.490287742927, 936.765808547863, 946.6071580294239, 958.5782255807422, 965.0, 1006.0112891316114, 8877.422967507091, 9016.8647470288, 9468.384555003902, 10676.145908327315, 10846.78306433867, 589418.0, 591747.0, 595672.0, 602565.0, 602706.0, 126948.0, 131318.68681313255, 136708.31645841905, 139397.0, 141886.29056854857, 977.7408986618552, 1005.2259759984771, 1055.9155267578576, 1057.724991502215, 1063.7393838509615, 18272.0, 18509.35217267389, 18630.068496574844, 18834.5815922535, 18889.0, 51054.744726276134, 51625.741871290404, 53109.20336195037, 53324.2001369972, 53445.7327713359, 57047.0, 72392.0, 74898.8765168512, 77249.61375193088, 80736.59631701803, 6690.565113267622, 7750.302472777477, 8104.635291411782, 8209.302209312274, 8460.407771456184, 9034.954825225832, 11767.588134415255, 11788.4695188715, 12139.635810925687, 12287.75424491523, 10594.2584019121, 10644.733881653021, 10978.615748448767, 11036.779264414827, 11071.50178241965, 10317.690469285933, 10361.22290828206, 10385.376877387382, 10702.304350217211, 10887.836682449928, 10868.021878031015, 10873.565057397616, 11086.61196858106, 11259.0, 11284.887151128383, 7940.960295198487, 8157.959210203911, 8161.877571836309, 8230.42387032902, 8402.11777763326, 9490.762730931781, 10780.407077610807, 10808.0, 10870.945645271722, 11001.504932277907], "value": 9442.310022255144, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.822272215973, 1591.1089789717755, 1622.3186261770059, 1814.2652225848535, 1931.4881556387552, 7763.146053934062, 8487.15128487151, 8724.171203735636, 8734.694285699994, 8864.379493435463, 8447.23974842264, 8726.301895848334, 8735.86896196556, 8739.082396348387, 8743.475391476515, 2115.9788402116014, 2196.923107691231, 2351.1535847095033, 2394.221877889684, 2431.7081950165916, 2027.8681885677383, 2456.91400800972, 2460.065175233409, 2461.7661322174454, 2466.334089795754, 2371.2174982255815, 2409.3736727708815, 2413.1312727418117, 2416.8429052111646, 2420.177139772477, 2097.92657256996, 2303.3205204464557, 2348.7886090251873, 2380.0, 2381.5594115088766, 1989.7413336266338, 2410.541997020578, 2427.1504973259393, 2429.9028038878423, 2433.209207007712, 8529.10444403335, 8601.827963440759, 8605.440646357967, 8609.009963854174, 8615.181557752003, 5148.794048238066, 5277.762500687479, 5297.073012222845, 5304.257403963448, 5308.628396012281, 2784.610154578361, 3327.584051993501, 3427.708644765173, 3446.2590543033366, 3450.7756995795444, 1041.8020576090594, 1044.269011691811, 1045.8222102242562, 1048.2452634103433, 1061.3790932304612, 9626.711198664052, 11542.0, 11588.594399196072, 11608.187426880122, 11609.18735688502, 4023.396490526386, 4612.76936153191, 4789.712617242976, 4829.6860704054125, 4880.487548807395, 9736.513174341255, 9776.755581110529, 9785.951070244746, 9786.070323319273, 9788.0, 5277.551408130275, 5282.075636763551, 5313.335833020872, 5326.707031113326, 5333.493318134771, 4346.782660866944, 4484.8430304939175, 4734.242521196592, 4879.829205977774, 4906.190478570996, 3836.3286424875537, 3872.186840763471, 3954.4661470701662, 3995.9400608990886, 4057.857974970862, 1315.953941612058, 1334.4128583423262, 1338.6519504928788, 1344.8050032745198, 1354.6748780292855, 819.4141189049858, 896.0815164456484, 909.086368199968, 948.0330063335359, 949.2453499467939, 5390.595705322038, 5671.971640141773, 5764.164196191528, 5797.855053623609, 5824.912626310609, 919.8390281700675, 948.1798244518442, 958.3579002068631, 963.3160456076264, 1013.0983424751977, 3197.6802319768303, 3418.3163367326633, 3721.4975978243133, 3784.3566593679143, 3806.5241844769403, 9515.857262141073, 9576.616935322649, 10693.144548436107, 10724.946375268073, 10853.837192442119, 10274.897251027394, 10676.572937082585, 10697.41164235959, 10780.568777248822, 10794.028537431512, 7999.920000800034, 8163.918360816316, 8182.754517364369, 8194.754157375288, 8278.586070696441, 5585.190147428519, 5667.886642267049, 5716.571257155813, 5779.682117483579, 5798.072308430631, 9882.11061004513, 9925.404475731479, 10027.699169024649, 10030.949845250727, 10042.748931276776, 9993.850092248767, 10032.448215348226, 10045.748856278651, 10117.443540605338, 10265.538050787583, 6460.418562329413, 6664.66676666155, 6668.633225172662, 6948.6178260196175, 7076.115485564304, 7795.922040779519, 7878.763637090897, 7881.684732610632, 7951.880721789062, 8218.178182181973, 7188.317109874553, 7366.263373662591, 7415.592142432218, 7516.736914207976, 7581.3176814087, 7022.613756243456, 7218.494705370784, 7258.818529536593, 7294.63526823646, 7340.669669864762, 6875.346842049998, 6968.268331825086, 6983.266756990646, 6993.2657071006815, 7017.333353331425, 4522.208613492691, 4530.365748795237, 4636.907261854676, 4709.905801884011, 4943.876403089952, 4610.677252592286, 4771.928421073756, 4794.520547945178, 4888.706677599355, 5030.195168772979], "value": 4515.911398978255, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.77142143392456, 316.6311247396781, 319.2258772476743, 323.6827908649522, 326.5933912279213, 5045.217991211368, 5091.389033316007, 5212.1660534314515, 5243.764030618622, 5295.761690723922, 4411.713238639486, 4589.862304130873, 4814.7351895645725, 5267.920981185285, 5280.709560974145, 3798.8860334190017, 3813.2564149990762, 3945.5068116485295, 3963.9189425551526, 3984.5417776955587, 3887.9805600971954, 3981.8696997106554, 3996.8401263949554, 3999.7400168988925, 4022.275990321741, 3600.6219346968583, 3606.098475381155, 3728.5152930119048, 3949.4273330367223, 3951.9802400988096, 3210.186244771708, 3749.531308586427, 3778.9244215115546, 3936.783476908769, 3938.960610393917, 2896.8551572421507, 3309.1561651778643, 3420.6066302375298, 3481.477778333245, 3505.5793304803333, 4348.434703488558, 4469.351943968107, 4666.919832910263, 4998.675086119427, 5051.141305977956, 3986.1828325193264, 4268.679849011337, 4478.619317358029, 4522.773861306954, 4575.76454357325, 4030.858919937788, 4713.387259656257, 5393.622446428752, 5432.348118225798, 5527.987246950422, 3304.735621150302, 3824.6557809796973, 3831.4252862070634, 3853.1523064925673, 3905.7070719695853, 4319.092990472004, 4520.897782531106, 4763.880902977418, 5562.972185139089, 5666.5183459406, 3128.515080162578, 3400.795952242873, 3858.2862170498547, 3865.40086286626, 3874.643874643852, 3232.660570640096, 3511.7366197534775, 3699.463577781207, 3897.279003384383, 4045.9190816183514, 4082.3264161413035, 4155.501339839238, 4294.003288470876, 4326.567343265711, 4569.885752856205, 4423.955760442419, 5289.814856480081, 5416.241726158341, 5470.510917532513, 5490.090252166358, 1145.2956431794387, 1247.2828123828654, 1286.4275397448046, 1363.175278956237, 1363.5636596289091, 723.9348458638748, 765.6784150656738, 794.0471434278888, 803.7789607857886, 843.6920524008815, 4317.222899877989, 4677.64917631179, 4991.0, 5091.523458197082, 5196.92204616931, 5394.811181608625, 5676.091825307931, 5743.741531631086, 5754.712264386764, 5791.58296605005, 4739.312799644033, 4906.828261010847, 5139.922901156486, 5156.510131537498, 5192.636515443958, 2556.8366393291008, 2925.3033240720333, 2971.0, 2973.3755911258877, 2991.1624745071413, 5497.752601132958, 5645.23789288449, 5834.358220595731, 5844.503217226498, 5986.5210783137245, 5011.674241174312, 5576.302962129734, 5690.77236910519, 5764.884702305931, 5801.680907550125, 4498.515489888395, 5181.35233095863, 5186.0, 5811.94188058114, 5844.649321040752, 5273.551748101454, 5481.903619276081, 5625.5218306442775, 5651.4348565143155, 5659.547236221175, 4710.4347478302825, 4813.9300100956925, 5223.947760522498, 5536.889262214652, 5545.473180047889, 5177.637565370388, 5401.3248343957, 5796.652200867961, 5806.6806325650805, 5903.940960590339, 2948.6903875092808, 2980.5231163013814, 3319.385913605894, 3327.5175099609937, 3340.7828491148, 4074.5942649785775, 4819.421669399694, 5196.922046169386, 5242.816501422432, 5681.289838770153, 4844.795617993875, 5141.511556402136, 5433.1035379161995, 5539.7230138492105, 5597.908407860428, 4623.3758442609815, 5153.793848246178, 5214.97392513035, 5532.589189756586, 5684.658920464785, 4772.785224664968, 5196.830713089525, 5306.8938621228135, 5310.654807437504, 5446.782128714807, 4834.613230941446, 4949.7030178189425, 5224.7721785379645, 5385.649932754359, 5522.723863806713, 4680.929786053144, 5357.571394288526, 5387.821185500574, 5419.268398766116, 5446.0], "value": 3921.1483235089167, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.207834168119, 729.1432566734089, 735.753522569939, 746.2201998911137, 754.7999780058288, 12277.69305767354, 14506.202158881271, 14691.632709182275, 14939.17834519101, 14952.177630230333, 14163.150210987347, 14383.137011779301, 14402.711945761079, 14410.855891441082, 14417.207053612074, 712.9394001509878, 729.4419768876813, 898.2454738020078, 916.3493919317293, 919.5723988345418, 896.4397251717677, 914.5792935249793, 917.9862302065475, 930.0978051290238, 930.9534523273843, 965.2712202287263, 974.7855471796194, 975.3465178330536, 976.1897624971257, 977.7115750853516, 751.7481643649384, 765.1392183793232, 765.9502132361379, 964.662368171138, 968.7674958010062, 572.9770809167628, 575.1947273816648, 700.8773464643667, 712.1454254894147, 714.1537278325201, 14576.052556583789, 14746.967712260146, 14812.851871481254, 14824.481143159937, 14831.258437078102, 12029.639110826602, 12035.338056406894, 12120.45457954394, 12233.143679942319, 12345.074119440987, 10514.264001519896, 10641.73395665107, 10685.732856678567, 11064.17018723591, 11218.102551795837, 3905.7265991380614, 3937.27160475313, 3957.465742124834, 3985.143194213258, 4114.280000999813, 77362.61318693426, 77385.61307193484, 78365.82451263236, 78573.60713196397, 78726.60636696836, 9148.359614827032, 9324.673636422693, 9354.144561347903, 9362.90637093634, 9627.374220675634, 16156.353745850269, 20837.166513339296, 20857.582848342947, 20863.791362086184, 20897.268595599384, 7035.894461583081, 7073.823154421078, 7124.145102587619, 7272.490925635155, 7286.562806231643, 3708.2583483303447, 3851.5378154621626, 3918.294706952776, 3956.584558621303, 3984.00399900025, 3342.6824451677053, 3452.4648679454726, 3454.7908232118557, 3457.6715212054814, 3462.8787992420143, 620.6462316479564, 627.2754968011953, 629.3077614623877, 638.5976834594176, 641.1985018726592, 518.3054706693049, 547.2639300141303, 566.2553741829563, 570.4694633990429, 572.2846441947565, 7622.656980435893, 7707.421943354271, 7809.765707028798, 7980.800479988046, 8215.548144852091, 493.331535769035, 514.8018013064997, 536.551979097458, 563.0681222576642, 600.4715850052014, 2746.5605503119405, 3242.6705050929004, 3502.667246611568, 3506.333796578642, 3903.1998440319667, 83761.16238837533, 85249.72125418123, 86078.0, 87133.56433217794, 88278.55860720655, 72434.27565724381, 75620.62189689017, 75700.0, 76165.0, 78844.60577697075, 67552.66223668752, 67597.0, 68039.31960680429, 68205.63588728297, 68243.0, 5968.850778730566, 7153.713851446092, 7220.819479513054, 7377.336039756233, 7554.924450755642, 42179.367309491, 42541.7237482876, 42830.78584607057, 43708.78145609252, 43737.0, 44821.77589112164, 59963.0, 60329.0, 60395.39604604073, 60477.0, 9432.481213533321, 9541.761455963657, 9722.75693107678, 9899.901000990187, 10188.976221073419, 8793.472391656522, 10360.740981475523, 10510.001549852752, 10606.257561970588, 10671.199660025373, 9595.28035397334, 9595.520223988913, 9771.85342219853, 10092.798144036931, 10145.137663298703, 9792.608295668093, 9818.214542836413, 9982.251331150337, 10130.949345253226, 10330.328528645614, 9817.85273220888, 9921.553530091307, 9967.40195588267, 9968.152707019985, 10018.248631352531, 8321.292690121407, 8509.021462531888, 8512.829743405222, 8578.356623253405, 8720.389572729848, 8299.6680132794, 8441.535715535705, 8495.895533580595, 8564.057953624972, 8876.423032502867], "value": 6497.420377693114, "unit": "score"}], "name": "dromaeo_css", "value": 7061.704611881061, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 112.0, 114.0, 113.0, 113.0, 112.0, 112.0, 112.0, 111.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 119.0, 143.0, 214.0, 142.0, 143.0, 141.0, 119.0, 174.0, 116.0], "value": 146.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 187.0, 187.0, 185.0, 185.0, 186.0, 182.0, 184.0, 187.0], "value": 185.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 71.0, 58.0, 127.0, 58.0, 59.0, 58.0, 60.0, 113.0, 56.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 102.0, 135.0, 80.0, 80.0, 77.0, 104.0, 80.0, 84.0, 103.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 83.0, 85.0, 83.0, 86.0, 83.0, 86.0, 83.0], "value": 84.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 92.0, 93.0, 92.0, 92.0, 92.0, 92.0, 92.0, 92.0], "value": 92.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 84.0, 84.0, 83.0, 79.0, 84.0, 87.0, 86.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 59.0, 57.0, 59.0, 122.0, 60.0, 58.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 76.0, 77.0, 72.0, 70.0, 72.0, 67.0, 68.0, 70.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 85.0, 84.0, 86.0, 86.0, 84.0, 85.0, 86.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 152.0, 125.0, 124.0, 142.0, 159.0, 147.0, 149.0, 236.0, 146.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 170.0, 169.0, 171.0, 170.0, 170.0, 169.0, 169.0, 165.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 67.0, 69.0, 68.0, 70.0, 78.0, 87.0, 87.0], "value": 72.7, "unit": "score"}], "name": "kraken", "value": 1487.0000000000002, "alertThreshold": 2.0}]} 01:06:01 INFO - Return code: 0 01:06:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 01:06:01 ERROR - # TBPL SUCCESS # 01:06:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:06:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:06:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:06:01 INFO - 2016-05-12 01:06:01.651 screenresolution[2518:33058] starting screenresolution argv=screenresolution get 01:06:01 INFO - 2016-05-12 01:06:01.654 screenresolution[2518:33058] Display 0: 1600x1200x32@60 01:06:01 INFO - 2016-05-12 01:06:01.661 screenresolution[2519:33061] starting screenresolution argv=screenresolution list 01:06:01 INFO - Available Modes on Display 0 01:06:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:06:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:06:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:06:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:06:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:06:01 INFO - Intel Iris: 01:06:01 INFO - Chipset Model: Intel Iris 01:06:01 INFO - Type: GPU 01:06:01 INFO - Bus: Built-In 01:06:01 INFO - VRAM (Dynamic, Max): 1536 MB 01:06:01 INFO - Vendor: Intel (0x8086) 01:06:01 INFO - Device ID: 0x0a2e 01:06:01 INFO - Revision ID: 0x0009 01:06:01 INFO - Displays: 01:06:01 INFO - Display: 01:06:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:06:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:06:01 INFO - Display Serial Number: 1600x1200 60 01:06:01 INFO - Main Display: Yes 01:06:01 INFO - Mirror: Off 01:06:01 INFO - Online: Yes 01:06:01 INFO - Rotation: Supported 01:06:01 INFO - Return code: 0 01:06:01 INFO - Running post-action listener: _resource_record_post_action 01:06:01 INFO - ##### 01:06:01 INFO - ##### Finished run-tests step (success) 01:06:01 INFO - ##### 01:06:01 INFO - Running post-run listener: _resource_record_post_run 01:06:02 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 12288; Write bytes: 692787712; Read time: 1; Write time: 2069 01:06:02 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 381661696; Write bytes: 376145408; Read time: 28746; Write time: 671 01:06:02 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 12288; Write bytes: 295990272; Read time: 1; Write time: 1339 01:06:02 INFO - Running post-run listener: _upload_blobber_files 01:06:02 INFO - Blob upload gear active. 01:06:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:06:02 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 01:06:02 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'] 01:06:02 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 01:06:03 INFO - (blobuploader) - INFO - Open directory for files ... 01:06:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:06:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:06:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:06:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:06:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:06:04 INFO - (blobuploader) - INFO - Done attempting. 01:06:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:06:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:06:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:06:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:06:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:06:05 INFO - (blobuploader) - INFO - Done attempting. 01:06:05 INFO - (blobuploader) - INFO - Iteration through files over. 01:06:05 INFO - Return code: 0 01:06:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:06:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:06:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8e459f0d186da9ae68c3d02e17fb2eacad532a7a2b129ded044b14be2081ef400711a9b2ee61aff1d75478f99fec7ad8872d50a5cf70df174c9b2b216bfc136", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9916e26570f88ac3fc52ba0b8990f6e274edc505cbf00ba4f5e51dc36478641441eeda23203cdaad2f7549c7bbffba75dc958afd1154fb1aca858dd269dd5b1d"} 01:06:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:06:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:06:05 INFO - Contents: 01:06:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8e459f0d186da9ae68c3d02e17fb2eacad532a7a2b129ded044b14be2081ef400711a9b2ee61aff1d75478f99fec7ad8872d50a5cf70df174c9b2b216bfc136", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9916e26570f88ac3fc52ba0b8990f6e274edc505cbf00ba4f5e51dc36478641441eeda23203cdaad2f7549c7bbffba75dc958afd1154fb1aca858dd269dd5b1d"} 01:06:05 INFO - Running post-run listener: copy_logs_to_upload_dir 01:06:05 INFO - Copying logs to upload dir... 01:06:05 INFO - mkdir: /builds/slave/test/build/upload/logs 01:06:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1332.790144 ========= master_lag: 1.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 13 secs) (at 2016-05-12 01:06:06.144724) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-05-12 01:06:06.148701) ========= 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.mWQWltQxfn/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.jyqN1XpwQi/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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/f8e459f0d186da9ae68c3d02e17fb2eacad532a7a2b129ded044b14be2081ef400711a9b2ee61aff1d75478f99fec7ad8872d50a5cf70df174c9b2b216bfc136", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9916e26570f88ac3fc52ba0b8990f6e274edc505cbf00ba4f5e51dc36478641441eeda23203cdaad2f7549c7bbffba75dc958afd1154fb1aca858dd269dd5b1d"} build_url:https://queue.taskcluster.net/v1/task/KEGrZltuQNqNDYHElfbiMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011205 build_url: 'https://queue.taskcluster.net/v1/task/KEGrZltuQNqNDYHElfbiMQ/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/f8e459f0d186da9ae68c3d02e17fb2eacad532a7a2b129ded044b14be2081ef400711a9b2ee61aff1d75478f99fec7ad8872d50a5cf70df174c9b2b216bfc136", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9916e26570f88ac3fc52ba0b8990f6e274edc505cbf00ba4f5e51dc36478641441eeda23203cdaad2f7549c7bbffba75dc958afd1154fb1aca858dd269dd5b1d"}' ========= master_lag: 4.30 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-05-12 01:06:10.458763) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-05-12 01:06:10.464170) ========= 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.mWQWltQxfn/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.jyqN1XpwQi/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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.004621 ========= master_lag: 2.75 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-05-12 01:06:13.220083) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 01:06:13.220470) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 01:06:13.417723) ========= ========= Total master_lag: 8.22 =========