builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0206 starttime: 1463832973.53 results: success (0) buildid: 20160521030227 builduid: e3a37a6f95074f9e8718fc5d3c0831cb revision: f1f2644d34440fe38c098e323bf570e09197e51a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-21 05:16:13.526161) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-21 05:16:13.526635) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-21 05:16:13.526957) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-21 05:16:13.594196) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 05:16:13.594548) ========= 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.OZ9lqZ7asN/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.KKpGJG9spt/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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-21 05:16:13-- 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% 7.94M=0.001s 2016-05-21 05:16:13 (7.94 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113249 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 05:16:13.729318) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 05:16:13.729814) ========= 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.OZ9lqZ7asN/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.KKpGJG9spt/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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.020022 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 05:16:13.783717) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-21 05:16:13.784304) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f1f2644d34440fe38c098e323bf570e09197e51a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f1f2644d34440fe38c098e323bf570e09197e51a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OZ9lqZ7asN/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.KKpGJG9spt/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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-21 05:16:13,802 truncating revision to first 12 chars 2016-05-21 05:16:13,802 Setting DEBUG logging. 2016-05-21 05:16:13,802 attempt 1/10 2016-05-21 05:16:13,802 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f1f2644d3444?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-21 05:16:15,559 unpacking tar archive at: mozilla-central-f1f2644d3444/testing/mozharness/ program finished with exit code 0 elapsedTime=2.010440 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-21 05:16:15.814702) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-21 05:16:15.815048) ========= script_repo_revision: f1f2644d34440fe38c098e323bf570e09197e51a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-21 05:16:15.815923) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-21 05:16:15.816236) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-21 05:16:15.831987) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 23 secs) (at 2016-05-21 05:16:15.832279) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OZ9lqZ7asN/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.KKpGJG9spt/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:16:15 INFO - MultiFileLogger online at 20160521 05:16:15 in /builds/slave/test 05:16:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:16:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:16:15 INFO - {'append_to_log': False, 05:16:15 INFO - 'base_work_dir': '/builds/slave/test', 05:16:15 INFO - 'blob_upload_branch': 'Firefox', 05:16:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:16:15 INFO - 'branch': 'Firefox', 05:16:15 INFO - 'buildbot_json_path': 'buildprops.json', 05:16:15 INFO - 'config_files': ('talos/mac_config.py',), 05:16:15 INFO - 'default_actions': ('clobber', 05:16:15 INFO - 'read-buildbot-config', 05:16:15 INFO - 'download-and-extract', 05:16:15 INFO - 'populate-webroot', 05:16:15 INFO - 'create-virtualenv', 05:16:15 INFO - 'install', 05:16:15 INFO - 'run-tests'), 05:16:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:16:15 INFO - 'download_minidump_stackwalk': True, 05:16:15 INFO - 'download_symbols': 'ondemand', 05:16:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:16:15 INFO - 'tooltool.py': '/tools/tooltool.py', 05:16:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:16:15 INFO - '/tools/misc-python/virtualenv.py')}, 05:16:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:16:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:16:15 INFO - 'installer_path': 'installer.exe', 05:16:15 INFO - 'log_level': 'info', 05:16:15 INFO - 'log_name': 'talos', 05:16:15 INFO - 'log_to_console': True, 05:16:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:16:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:16:15 INFO - 'opt_config_files': (), 05:16:15 INFO - 'pip_index': False, 05:16:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:16:15 INFO - 'cmd': ('bash', 05:16:15 INFO - '-c', 05:16:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:16:15 INFO - 'enabled': True, 05:16:15 INFO - 'halt_on_failure': False, 05:16:15 INFO - 'name': 'check_screen_resolution'},), 05:16:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:16:15 INFO - 'cmd': ('bash', 05:16:15 INFO - '-c', 05:16:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:16:15 INFO - 'enabled': True, 05:16:15 INFO - 'halt_on_failure': False, 05:16:15 INFO - 'name': 'check_screen_resolution'},), 05:16:15 INFO - 'run_cmd_checks_enabled': True, 05:16:15 INFO - 'sps_profile': False, 05:16:15 INFO - 'sps_profile_interval': 0, 05:16:15 INFO - 'suite': 'dromaeojs', 05:16:15 INFO - 'system_bits': '32', 05:16:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:16:15 INFO - 'title': 't-yosemite-r7-0206', 05:16:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:16:15 INFO - 'use_talos_json': True, 05:16:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:16:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:16:15 INFO - 'work_dir': 'build'} 05:16:15 INFO - ##### 05:16:15 INFO - ##### Running clobber step. 05:16:15 INFO - ##### 05:16:15 INFO - Running pre-action listener: _resource_record_pre_action 05:16:15 INFO - Running main action method: clobber 05:16:15 INFO - rmtree: /builds/slave/test/build 05:16:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:16:16 INFO - Running post-action listener: _resource_record_post_action 05:16:16 INFO - ##### 05:16:16 INFO - ##### Finished clobber step (success) 05:16:16 INFO - ##### 05:16:16 INFO - ##### 05:16:16 INFO - ##### Running read-buildbot-config step. 05:16:16 INFO - ##### 05:16:16 INFO - Running pre-action listener: _resource_record_pre_action 05:16:16 INFO - Running main action method: read_buildbot_config 05:16:16 INFO - Using buildbot properties: 05:16:16 INFO - { 05:16:16 INFO - "project": "", 05:16:16 INFO - "product": "firefox", 05:16:16 INFO - "script_repo_revision": "production", 05:16:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:16:16 INFO - "repository": "", 05:16:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:16:16 INFO - "buildid": "20160521030227", 05:16:16 INFO - "stage_platform": "macosx64", 05:16:16 INFO - "basedir": "/builds/slave/test", 05:16:16 INFO - "nightly_build": "True", 05:16:16 INFO - "buildnumber": 248, 05:16:16 INFO - "slavename": "t-yosemite-r7-0206", 05:16:16 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:16:16 INFO - "platform": "yosemite_r7", 05:16:16 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:16:16 INFO - "branch": "mozilla-central", 05:16:16 INFO - "repo_path": "mozilla-central", 05:16:16 INFO - "revision": "f1f2644d34440fe38c098e323bf570e09197e51a", 05:16:16 INFO - "pgo_build": "False", 05:16:16 INFO - "builduid": "e3a37a6f95074f9e8718fc5d3c0831cb", 05:16:16 INFO - "slavebuilddir": "test" 05:16:16 INFO - } 05:16:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:16:16 INFO - Running post-action listener: _resource_record_post_action 05:16:16 INFO - ##### 05:16:16 INFO - ##### Finished read-buildbot-config step (success) 05:16:16 INFO - ##### 05:16:16 INFO - ##### 05:16:16 INFO - ##### Running download-and-extract step. 05:16:16 INFO - ##### 05:16:16 INFO - Running pre-action listener: _resource_record_pre_action 05:16:16 INFO - Running main action method: download_and_extract 05:16:16 INFO - mkdir: /builds/slave/test/build/tests 05:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:16 INFO - https://queue.taskcluster.net/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:16:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:16:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:16:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:16:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 05:16:47 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json: timed out 05:16:47 INFO - retry: attempt #1 caught exception: timed out 05:16:47 INFO - retry: Failed, sleeping 30 seconds before retrying 05:17:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/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 #2 05:17:23 INFO - Downloaded 1199 bytes. 05:17:23 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:17:23 INFO - Using the following test package requirements: 05:17:23 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:17:23 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:23 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:17:23 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:23 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:17:23 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:23 INFO - u'jsshell-mac.zip'], 05:17:23 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:23 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:17:23 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:17:23 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:23 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:17:23 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:23 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:17:23 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:23 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:17:23 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:17:23 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:17:23 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:17: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')]} 05:17:23 INFO - https://queue.taskcluster.net/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:17:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:17:27 INFO - Downloaded 18630625 bytes. 05:17:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:17:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:17:30 INFO - Return code: 0 05:17:30 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:30 INFO - https://queue.taskcluster.net/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:17:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:17:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:17:38 INFO - Downloaded 18630625 bytes. 05:17:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:17:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:17:41 INFO - Return code: 0 05:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:41 INFO - https://queue.taskcluster.net/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:17:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:17:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:17:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:17:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:17:42 INFO - Downloaded 10840359 bytes. 05:17:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:17:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:17:42 INFO - Return code: 0 05:17: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')]} 05:17:42 INFO - https://queue.taskcluster.net/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:17:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:17:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:17:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:17:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 05:17:45 INFO - Downloaded 110310025 bytes. 05:17:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:17:45 INFO - mkdir: /builds/slave/test/properties 05:17:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:17:45 INFO - Writing to file /builds/slave/test/properties/build_url 05:17:45 INFO - Contents: 05:17:45 INFO - build_url:https://queue.taskcluster.net/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:17:46 INFO - Running post-action listener: _resource_record_post_action 05:17:46 INFO - Running post-action listener: set_extra_try_arguments 05:17:46 INFO - ##### 05:17:46 INFO - ##### Finished download-and-extract step (success) 05:17:46 INFO - ##### 05:17:46 INFO - ##### 05:17:46 INFO - ##### Running populate-webroot step. 05:17:46 INFO - ##### 05:17:46 INFO - Running pre-action listener: _resource_record_pre_action 05:17:46 INFO - Running main action method: populate_webroot 05:17:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:17:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:17:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:17:46 INFO - '--tppagecycles', 05:17:46 INFO - '7'], 05:17:46 INFO - 'tests': ['tsvgm']}}, 05:17:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:17:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:17:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:17:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:17:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:46 INFO - 'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:46 INFO - 'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['damp', 'tps']}, 05:17:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:46 INFO - 'tests': ['damp', 'tps']}, 05:17:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['dromaeo_dom']}, 05:17:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:17:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['a11yr', 05:17:46 INFO - 'ts_paint', 05:17:46 INFO - 'tpaint', 05:17:46 INFO - 'sessionrestore', 05:17:46 INFO - 'sessionrestore_no_auto_restore', 05:17:46 INFO - 'tabpaint']}, 05:17:46 INFO - 'other-e10s': {'tests': ['a11yr', 05:17:46 INFO - 'ts_paint', 05:17:46 INFO - 'tpaint', 05:17:46 INFO - 'sessionrestore', 05:17:46 INFO - 'sessionrestore_no_auto_restore', 05:17:46 INFO - 'tabpaint']}, 05:17:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:17:46 INFO - 'ts_paint', 05:17:46 INFO - 'tpaint', 05:17:46 INFO - 'sessionrestore', 05:17:46 INFO - 'sessionrestore_no_auto_restore', 05:17:46 INFO - 'tabpaint']}, 05:17:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:17:46 INFO - 'ts_paint', 05:17:46 INFO - 'tpaint', 05:17:46 INFO - 'sessionrestore', 05:17:46 INFO - 'sessionrestore_no_auto_restore', 05:17:46 INFO - 'tabpaint']}, 05:17:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['a11yr', 05:17:46 INFO - 'ts_paint', 05:17:46 INFO - 'tpaint', 05:17:46 INFO - 'sessionrestore', 05:17:46 INFO - 'sessionrestore_no_auto_restore', 05:17:46 INFO - 'tabpaint']}, 05:17:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['a11yr', 05:17:46 INFO - 'ts_paint', 05:17:46 INFO - 'tpaint', 05:17:46 INFO - 'sessionrestore', 05:17:46 INFO - 'sessionrestore_no_auto_restore', 05:17:46 INFO - 'tabpaint']}, 05:17:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['tsvgx', 05:17:46 INFO - 'tsvgr_opacity', 05:17:46 INFO - 'tart', 05:17:46 INFO - 'tscrollx', 05:17:46 INFO - 'cart']}, 05:17:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:17:46 INFO - 'tsvgr_opacity', 05:17:46 INFO - 'tart', 05:17:46 INFO - 'tscrollx', 05:17:46 INFO - 'cart']}, 05:17:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:46 INFO - 'talos_options': ['--disable-e10s'], 05:17:46 INFO - 'tests': ['tp5o']}, 05:17:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:46 INFO - 'tests': ['tp5o']}, 05:17:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:46 INFO - 'talos_options': ['--disable-e10s', 05:17:46 INFO - '--xperf_path', 05:17:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:46 INFO - 'tests': ['tp5n']}, 05:17:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:46 INFO - 'talos_options': ['--xperf_path', 05:17:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:46 INFO - 'tests': ['tp5n']}}, 05:17:46 INFO - 'talos.zip': {'path': '', 05:17:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:17:46 INFO - Running post-action listener: _resource_record_post_action 05:17:46 INFO - ##### 05:17:46 INFO - ##### Finished populate-webroot step (success) 05:17:46 INFO - ##### 05:17:46 INFO - ##### 05:17:46 INFO - ##### Running create-virtualenv step. 05:17:46 INFO - ##### 05:17:46 INFO - Running pre-action listener: _resource_record_pre_action 05:17:46 INFO - Running main action method: create_virtualenv 05:17:46 INFO - Creating virtualenv /builds/slave/test/build/venv 05:17:46 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:17:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:17:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:17:46 INFO - Using real prefix '/tools/python27' 05:17:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:17:47 INFO - Installing distribute.............................................................................................................................................................................................done. 05:17:50 INFO - Installing pip.................done. 05:17:50 INFO - Return code: 0 05:17:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:50 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 0x10aeb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b1d7ca20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad5bd30>, '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 0x10ab7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad52b20>, '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.OZ9lqZ7asN/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.KKpGJG9spt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:17:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ9lqZ7asN/Render', 05:17:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:50 INFO - 'HOME': '/Users/cltbld', 05:17:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:50 INFO - 'LOGNAME': 'cltbld', 05:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:50 INFO - 'MOZ_NO_REMOTE': '1', 05:17:50 INFO - 'NO_EM_RESTART': '1', 05:17:50 INFO - 'PAGER': '/bin/cat', 05:17:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:50 INFO - 'PWD': '/builds/slave/test', 05:17:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:50 INFO - 'SHELL': '/bin/bash', 05:17:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKpGJG9spt/Listeners', 05:17:50 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 05:17:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:50 INFO - 'USER': 'cltbld', 05:17:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:50 INFO - 'XPC_FLAGS': '0x0', 05:17:50 INFO - 'XPC_SERVICE_NAME': '0', 05:17:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:17:50 INFO - Cleaning up... 05:17:50 INFO - Return code: 0 05:17:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b1d7ca20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad5bd30>, '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 0x10ab7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad52b20>, '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.OZ9lqZ7asN/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.KKpGJG9spt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:17:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ9lqZ7asN/Render', 05:17:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:50 INFO - 'HOME': '/Users/cltbld', 05:17:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:50 INFO - 'LOGNAME': 'cltbld', 05:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:50 INFO - 'MOZ_NO_REMOTE': '1', 05:17:50 INFO - 'NO_EM_RESTART': '1', 05:17:50 INFO - 'PAGER': '/bin/cat', 05:17:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:50 INFO - 'PWD': '/builds/slave/test', 05:17:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:50 INFO - 'SHELL': '/bin/bash', 05:17:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKpGJG9spt/Listeners', 05:17:50 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 05:17:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:50 INFO - 'USER': 'cltbld', 05:17:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:50 INFO - 'XPC_FLAGS': '0x0', 05:17:50 INFO - 'XPC_SERVICE_NAME': '0', 05:17:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:50 INFO - Downloading/unpacking psutil>=3.1.1 05:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:17:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:54 INFO - Installing collected packages: psutil 05:17:54 INFO - Running setup.py install for psutil 05:17:54 INFO - building 'psutil._psutil_osx' extension 05:17:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:17:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:17:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:17:54 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:17:54 INFO - building 'psutil._psutil_posix' extension 05:17:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:17:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:17:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:17:54 INFO - ^ 05:17:54 INFO - 1 warning generated. 05:17:54 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:17:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:55 INFO - Successfully installed psutil 05:17:55 INFO - Cleaning up... 05:17:55 INFO - Return code: 0 05:17:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:17: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')]} 05:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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')]} 05:17:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b1d7ca20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad5bd30>, '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 0x10ab7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad52b20>, '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.OZ9lqZ7asN/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.KKpGJG9spt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:17:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ9lqZ7asN/Render', 05:17:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:55 INFO - 'HOME': '/Users/cltbld', 05:17:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:55 INFO - 'LOGNAME': 'cltbld', 05:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:55 INFO - 'MOZ_NO_REMOTE': '1', 05:17:55 INFO - 'NO_EM_RESTART': '1', 05:17:55 INFO - 'PAGER': '/bin/cat', 05:17:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:55 INFO - 'PWD': '/builds/slave/test', 05:17:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:55 INFO - 'SHELL': '/bin/bash', 05:17:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKpGJG9spt/Listeners', 05:17:55 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 05:17:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:55 INFO - 'USER': 'cltbld', 05:17:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:55 INFO - 'XPC_FLAGS': '0x0', 05:17:55 INFO - 'XPC_SERVICE_NAME': '0', 05:17:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:17:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:17:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:17:57 INFO - Installing collected packages: mozsystemmonitor 05:17:57 INFO - Running setup.py install for mozsystemmonitor 05:17:58 INFO - Successfully installed mozsystemmonitor 05:17:58 INFO - Cleaning up... 05:17:58 INFO - Return code: 0 05:17:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10aeb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b1d7ca20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad5bd30>, '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 0x10ab7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad52b20>, '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.OZ9lqZ7asN/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.KKpGJG9spt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:17:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ9lqZ7asN/Render', 05:17:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:58 INFO - 'HOME': '/Users/cltbld', 05:17:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:58 INFO - 'LOGNAME': 'cltbld', 05:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:58 INFO - 'MOZ_NO_REMOTE': '1', 05:17:58 INFO - 'NO_EM_RESTART': '1', 05:17:58 INFO - 'PAGER': '/bin/cat', 05:17:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:58 INFO - 'PWD': '/builds/slave/test', 05:17:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:58 INFO - 'SHELL': '/bin/bash', 05:17:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKpGJG9spt/Listeners', 05:17:58 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 05:17:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:58 INFO - 'USER': 'cltbld', 05:17:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:58 INFO - 'XPC_FLAGS': '0x0', 05:17:58 INFO - 'XPC_SERVICE_NAME': '0', 05:17:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:58 INFO - Downloading/unpacking blobuploader==1.2.4 05:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:00 INFO - Downloading blobuploader-1.2.4.tar.gz 05:18:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:18:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:18:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:01 INFO - Downloading docopt-0.6.1.tar.gz 05:18:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:18:02 INFO - Installing collected packages: blobuploader, requests, docopt 05:18:02 INFO - Running setup.py install for blobuploader 05:18:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:18:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:18:02 INFO - Running setup.py install for requests 05:18:02 INFO - Running setup.py install for docopt 05:18:02 INFO - Successfully installed blobuploader requests docopt 05:18:02 INFO - Cleaning up... 05:18:02 INFO - Return code: 0 05:18:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:02 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 0x10aeb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b1d7ca20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad5bd30>, '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 0x10ab7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad52b20>, '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.OZ9lqZ7asN/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.KKpGJG9spt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:18:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ9lqZ7asN/Render', 05:18:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:02 INFO - 'HOME': '/Users/cltbld', 05:18:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:02 INFO - 'LOGNAME': 'cltbld', 05:18:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:02 INFO - 'MOZ_NO_REMOTE': '1', 05:18:02 INFO - 'NO_EM_RESTART': '1', 05:18:02 INFO - 'PAGER': '/bin/cat', 05:18:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:02 INFO - 'PWD': '/builds/slave/test', 05:18:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:02 INFO - 'SHELL': '/bin/bash', 05:18:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKpGJG9spt/Listeners', 05:18:02 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 05:18:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:02 INFO - 'USER': 'cltbld', 05:18:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:02 INFO - 'XPC_FLAGS': '0x0', 05:18:02 INFO - 'XPC_SERVICE_NAME': '0', 05:18:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:03 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-SNXVfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:03 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-2Ez44k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:03 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-bfrkpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:03 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-M5hQ5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:03 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-U2RrOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:03 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-vu163d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:03 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-1TVkKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:03 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-MWFjTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:04 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-8hoDo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:04 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-UjWTcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:04 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-1zmqVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:04 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-244TK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:04 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-KDt601-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:04 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-K_WJDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:04 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-SnXUDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:04 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-YRiAbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:04 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-iFI0Ba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:18:05 INFO - Running setup.py install for manifestparser 05:18:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:18:05 INFO - Running setup.py install for mozcrash 05:18:05 INFO - Running setup.py install for mozdebug 05:18:05 INFO - Running setup.py install for mozdevice 05:18:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:18:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:18:05 INFO - Running setup.py install for mozfile 05:18:05 INFO - Running setup.py install for mozhttpd 05:18:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:18:05 INFO - Running setup.py install for mozinfo 05:18:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Running setup.py install for mozInstall 05:18:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Running setup.py install for mozleak 05:18:06 INFO - Running setup.py install for mozlog 05:18:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Running setup.py install for moznetwork 05:18:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Running setup.py install for mozprocess 05:18:06 INFO - Running setup.py install for mozprofile 05:18:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:18:06 INFO - Running setup.py install for mozrunner 05:18:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:18:07 INFO - Running setup.py install for mozscreenshot 05:18:07 INFO - Running setup.py install for moztest 05:18:07 INFO - Running setup.py install for mozversion 05:18:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:18:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:18:07 INFO - Cleaning up... 05:18:07 INFO - Return code: 0 05:18:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:07 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 0x10aeb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b1d7ca20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad5bd30>, '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 0x10ab7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad52b20>, '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.OZ9lqZ7asN/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.KKpGJG9spt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ9lqZ7asN/Render', 05:18:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:07 INFO - 'HOME': '/Users/cltbld', 05:18:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:07 INFO - 'LOGNAME': 'cltbld', 05:18:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:07 INFO - 'MOZ_NO_REMOTE': '1', 05:18:07 INFO - 'NO_EM_RESTART': '1', 05:18:07 INFO - 'PAGER': '/bin/cat', 05:18:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:07 INFO - 'PWD': '/builds/slave/test', 05:18:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:07 INFO - 'SHELL': '/bin/bash', 05:18:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKpGJG9spt/Listeners', 05:18:07 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 05:18:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:07 INFO - 'USER': 'cltbld', 05:18:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:07 INFO - 'XPC_FLAGS': '0x0', 05:18:07 INFO - 'XPC_SERVICE_NAME': '0', 05:18:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:07 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-nbzBxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:07 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:07 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-pJTK4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:08 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-T002XM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:08 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-54L9eL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:08 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-m8QeyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:08 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-efjGvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:08 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-3TqP6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:08 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-JlpSog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:08 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-i4A3QC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:08 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-QpC0Lg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:08 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-47_jlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:09 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-qsudUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:09 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-a1Ktov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:09 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-VDGFf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:09 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-ZYY078-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:09 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-iFsFqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:09 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-FSzXVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:12 INFO - Downloading blessings-1.6.tar.gz 05:18:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:18:12 INFO - Installing collected packages: blessings 05:18:12 INFO - Running setup.py install for blessings 05:18:12 INFO - Successfully installed blessings 05:18:12 INFO - Cleaning up... 05:18:12 INFO - Return code: 0 05:18:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:18:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:18:12 INFO - Reading from file tmpfile_stdout 05:18:12 INFO - Current package versions: 05:18:12 INFO - blessings == 1.6 05:18:12 INFO - blobuploader == 1.2.4 05:18:12 INFO - docopt == 0.6.1 05:18:12 INFO - manifestparser == 1.1 05:18:12 INFO - mozInstall == 1.12 05:18:12 INFO - mozcrash == 0.17 05:18:12 INFO - mozdebug == 0.1 05:18:12 INFO - mozdevice == 0.48 05:18:12 INFO - mozfile == 1.2 05:18:12 INFO - mozhttpd == 0.7 05:18:12 INFO - mozinfo == 0.9 05:18:12 INFO - mozleak == 0.1 05:18:12 INFO - mozlog == 3.2 05:18:12 INFO - moznetwork == 0.27 05:18:12 INFO - mozprocess == 0.22 05:18:12 INFO - mozprofile == 0.28 05:18:12 INFO - mozrunner == 6.11 05:18:12 INFO - mozscreenshot == 0.1 05:18:12 INFO - mozsystemmonitor == 0.3 05:18:12 INFO - moztest == 0.7 05:18:12 INFO - mozversion == 1.4 05:18:12 INFO - psutil == 3.1.1 05:18:12 INFO - requests == 1.2.3 05:18:12 INFO - wsgiref == 0.1.2 05:18:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:12 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 0x10aeb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b1d7ca20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad5bd30>, '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 0x10ab7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad52b20>, '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.OZ9lqZ7asN/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.KKpGJG9spt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ9lqZ7asN/Render', 05:18:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:12 INFO - 'HOME': '/Users/cltbld', 05:18:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:12 INFO - 'LOGNAME': 'cltbld', 05:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:12 INFO - 'MOZ_NO_REMOTE': '1', 05:18:12 INFO - 'NO_EM_RESTART': '1', 05:18:12 INFO - 'PAGER': '/bin/cat', 05:18:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:12 INFO - 'PWD': '/builds/slave/test', 05:18:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:12 INFO - 'SHELL': '/bin/bash', 05:18:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKpGJG9spt/Listeners', 05:18:12 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 05:18:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:12 INFO - 'USER': 'cltbld', 05:18:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:12 INFO - 'XPC_FLAGS': '0x0', 05:18:12 INFO - 'XPC_SERVICE_NAME': '0', 05:18:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:18:13 INFO - Cleaning up... 05:18:13 INFO - Return code: 0 05:18:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:13 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 0x10aeb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b1d7ca20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad5bd30>, '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 0x10ab7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad52b20>, '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.OZ9lqZ7asN/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.KKpGJG9spt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:18:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ9lqZ7asN/Render', 05:18:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:13 INFO - 'HOME': '/Users/cltbld', 05:18:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:13 INFO - 'LOGNAME': 'cltbld', 05:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:13 INFO - 'MOZ_NO_REMOTE': '1', 05:18:13 INFO - 'NO_EM_RESTART': '1', 05:18:13 INFO - 'PAGER': '/bin/cat', 05:18:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:13 INFO - 'PWD': '/builds/slave/test', 05:18:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:13 INFO - 'SHELL': '/bin/bash', 05:18:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKpGJG9spt/Listeners', 05:18:13 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 05:18:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:13 INFO - 'USER': 'cltbld', 05:18:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:13 INFO - 'XPC_FLAGS': '0x0', 05:18:13 INFO - 'XPC_SERVICE_NAME': '0', 05:18:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:13 INFO - Downloading/unpacking jsonschema 05:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:18:15 INFO - Downloading/unpacking functools32 (from jsonschema) 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18:16 INFO - Downloading functools32-3.2.3-2.tar.gz 05:18:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:18:16 INFO - warning: no files found matching '*.txt' 05:18:16 INFO - no previously-included directories found matching 'build' 05:18:16 INFO - no previously-included directories found matching 'dist' 05:18:16 INFO - no previously-included directories found matching '.git*' 05:18:16 INFO - Installing collected packages: jsonschema, functools32 05:18:16 INFO - Running setup.py install for functools32 05:18:16 INFO - warning: no files found matching '*.txt' 05:18:16 INFO - no previously-included directories found matching 'build' 05:18:16 INFO - no previously-included directories found matching 'dist' 05:18:16 INFO - no previously-included directories found matching '.git*' 05:18:16 INFO - Successfully installed jsonschema functools32 05:18:16 INFO - Cleaning up... 05:18:16 INFO - Return code: 0 05:18:16 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aebd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b1d7ca20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad5bd30>, '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 0x10ab7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad52b20>, '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.OZ9lqZ7asN/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.KKpGJG9spt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:18:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:18:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ9lqZ7asN/Render', 05:18:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:16 INFO - 'HOME': '/Users/cltbld', 05:18:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:16 INFO - 'LOGNAME': 'cltbld', 05:18:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:16 INFO - 'MOZ_NO_REMOTE': '1', 05:18:16 INFO - 'NO_EM_RESTART': '1', 05:18:16 INFO - 'PAGER': '/bin/cat', 05:18:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:16 INFO - 'PWD': '/builds/slave/test', 05:18:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:16 INFO - 'SHELL': '/bin/bash', 05:18:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKpGJG9spt/Listeners', 05:18:16 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 05:18:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:16 INFO - 'USER': 'cltbld', 05:18:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:16 INFO - 'XPC_FLAGS': '0x0', 05:18:16 INFO - 'XPC_SERVICE_NAME': '0', 05:18:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:16 INFO - Downloading/unpacking flake8 05:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:18:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:18:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:18:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:18:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:18:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:18:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:18:20 INFO - no previously-included directories found matching 'docs/_build' 05:18:20 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:18:21 INFO - Running setup.py install for pep8 05:18:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:18:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:18:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:18:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:18:21 INFO - no previously-included directories found matching 'docs/_build' 05:18:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:18:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:18:21 INFO - Cleaning up... 05:18:21 INFO - Return code: 0 05:18:21 INFO - Running post-action listener: _resource_record_post_action 05:18:21 INFO - Running post-action listener: _start_resource_monitoring 05:18:21 INFO - Starting resource monitoring. 05:18:21 INFO - ##### 05:18:21 INFO - ##### Finished create-virtualenv step (success) 05:18:21 INFO - ##### 05:18:21 INFO - ##### 05:18:21 INFO - ##### Running install step. 05:18:21 INFO - ##### 05:18:21 INFO - Running pre-action listener: _resource_record_pre_action 05:18:21 INFO - Running main action method: install 05:18:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:18:21 INFO - Reading from file tmpfile_stdout 05:18:21 INFO - Detecting whether we're running mozinstall >=1.0... 05:18:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:18:21 INFO - Reading from file tmpfile_stdout 05:18:21 INFO - Output received: 05:18:21 INFO - Usage: mozinstall [options] installer 05:18:21 INFO - Options: 05:18:21 INFO - -h, --help show this help message and exit 05:18:21 INFO - -d DEST, --destination=DEST 05:18:21 INFO - Directory to install application into. [default: 05:18:21 INFO - "/builds/slave/test"] 05:18:21 INFO - --app=APP Application being installed. [default: firefox] 05:18:21 INFO - mkdir: /builds/slave/test/build/application 05:18:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:18:55 INFO - Reading from file tmpfile_stdout 05:18:55 INFO - Output received: 05:18:55 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:18:55 INFO - Running post-action listener: _resource_record_post_action 05:18:55 INFO - ##### 05:18:55 INFO - ##### Finished install step (success) 05:18:55 INFO - ##### 05:18:55 INFO - ##### 05:18:55 INFO - ##### Running run-tests step. 05:18:55 INFO - ##### 05:18:55 INFO - Running pre-action listener: _resource_record_pre_action 05:18:55 INFO - Running main action method: run_tests 05:18:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:18:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:18:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:18:55 INFO - 2016-05-21 05:18:55.845 screenresolution[1946:12388] starting screenresolution argv=screenresolution get 05:18:55 INFO - 2016-05-21 05:18:55.848 screenresolution[1946:12388] Display 0: 1600x1200x32@60 05:18:55 INFO - 2016-05-21 05:18:55.854 screenresolution[1947:12390] starting screenresolution argv=screenresolution list 05:18:55 INFO - Available Modes on Display 0 05:18:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:18:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:18:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:18:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:18:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:18:56 INFO - Intel Iris: 05:18:56 INFO - Chipset Model: Intel Iris 05:18:56 INFO - Type: GPU 05:18:56 INFO - Bus: Built-In 05:18:56 INFO - VRAM (Dynamic, Max): 1536 MB 05:18:56 INFO - Vendor: Intel (0x8086) 05:18:56 INFO - Device ID: 0x0a2e 05:18:56 INFO - Revision ID: 0x0009 05:18:56 INFO - Displays: 05:18:56 INFO - Display: 05:18:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:18:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:18:56 INFO - Display Serial Number: 1600x1200 60 05:18:56 INFO - Main Display: Yes 05:18:56 INFO - Mirror: Off 05:18:56 INFO - Online: Yes 05:18:56 INFO - Rotation: Supported 05:18:56 INFO - Return code: 0 05:18:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:18:56 INFO - Python 2.7.3 05:18:56 INFO - Return code: 0 05:18:56 INFO - grabbing minidump binary from tooltool 05:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:56 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 0x10ad5bd30>, '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 0x10ab7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad52b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:18:56 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:18:56 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:18:56 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:18:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:18:56 INFO - Return code: 0 05:18:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:18:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:18:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:18:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:18:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:18:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:18:57 INFO - Return code: 0 05:18:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0206', '--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 05:18:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0206 --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 05:18:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ9lqZ7asN/Render', 05:18:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:57 INFO - 'HOME': '/Users/cltbld', 05:18:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:57 INFO - 'LOGNAME': 'cltbld', 05:18:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:18:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:57 INFO - 'MOZ_NO_REMOTE': '1', 05:18:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:18:57 INFO - 'NO_EM_RESTART': '1', 05:18:57 INFO - 'PAGER': '/bin/cat', 05:18:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:57 INFO - 'PWD': '/builds/slave/test', 05:18:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:18:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:57 INFO - 'SHELL': '/bin/bash', 05:18:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KKpGJG9spt/Listeners', 05:18:57 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 05:18:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:57 INFO - 'USER': 'cltbld', 05:18:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:57 INFO - 'XPC_FLAGS': '0x0', 05:18:57 INFO - 'XPC_SERVICE_NAME': '0', 05:18:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0206', '--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 05:18:57 INFO - mozversion application_buildid: 20160521030227 05:18:57 INFO - mozversion application_changeset: f1f2644d34440fe38c098e323bf570e09197e51a 05:18:57 INFO - mozversion application_display_name: Nightly 05:18:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:18:57 INFO - mozversion application_name: Firefox 05:18:57 INFO - mozversion application_remotingname: firefox 05:18:57 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:18:57 INFO - mozversion application_vendor: Mozilla 05:18:57 INFO - mozversion application_version: 49.0a1 05:18:57 INFO - mozversion platform_buildid: 20160521030227 05:18:57 INFO - mozversion platform_changeset: f1f2644d34440fe38c098e323bf570e09197e51a 05:18:57 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:18:57 INFO - mozversion platform_version: 49.0a1 05:18:57 INFO - using testdate: 1463833137 05:18:57 INFO - actual date: 1463833137 05:18:57 INFO - starting webserver on 'localhost:49673' 05:18:57 INFO - SUITE-START | Running 2 tests 05:18:57 INFO - TEST-START | dromaeo_css 05:18:57 INFO - operating with platform_type : mac_ 05:18:57 INFO - Initialising browser for dromaeo_css test... 05:18:57 INFO - TEST-INFO | started process 1965 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpNqbQNJ/profile http://localhost:49673/getInfo.html) 05:18:59 INFO - PROCESS | 1965 | __metrics Screen width/height:1600/1200 05:18:59 INFO - PROCESS | 1965 | colorDepth:24 05:18:59 INFO - PROCESS | 1965 | Browser inner width/height: 1024/689 05:18:59 INFO - PROCESS | 1965 | __metrics 05:18:59 INFO - PROCESS | 1965 | 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" 05:18:59 ERROR - PROCESS | 1965 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:19:00 INFO - TEST-INFO | 1965: exit 0 05:19:00 INFO - Browser initialized. 05:19:00 INFO - Running cycle 1/1 for dromaeo_css test... 05:19:00 INFO - TEST-INFO | started process 1968 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpNqbQNJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:19:12 INFO - PROCESS | 1968 | RSS: Main: 239034368 05:19:12 INFO - PROCESS | 1968 | 05:20:03 INFO - PROCESS | 1968 | 1463833203857 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] 05:22:14 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:49673/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49673/tests/dromaeo/cssquery-ext.html) 05:22:15 INFO - PROCESS | 1968 | RSS: Main: 332521472 05:22:15 INFO - PROCESS | 1968 | 05:25:18 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:49673/tests/dromaeo/cssquery-ext.html (next: http://localhost:49673/tests/dromaeo/cssquery-jquery.html) 05:25:18 INFO - PROCESS | 1968 | RSS: Main: 376881152 05:25:18 INFO - PROCESS | 1968 | 05:28:20 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:49673/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49673/tests/dromaeo/cssquery-mootools.html) 05:28:21 INFO - PROCESS | 1968 | RSS: Main: 372576256 05:28:21 INFO - PROCESS | 1968 | 05:31:23 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:49673/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49673/tests/dromaeo/cssquery-prototype.html) 05:31:24 INFO - PROCESS | 1968 | RSS: Main: 370098176 05:31:24 INFO - PROCESS | 1968 | 05:34:27 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:49673/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49673/tests/dromaeo/cssquery-yui.html) 05:34:28 INFO - PROCESS | 1968 | RSS: Main: 336023552 05:34:28 INFO - PROCESS | 1968 | 05:37:30 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:49673/tests/dromaeo/cssquery-yui.html (next: http://localhost:49673/tests/dromaeo/cssquery-dojo.html) 05:37:31 INFO - PROCESS | 1968 | RSS: Main: 373915648 05:37:31 INFO - PROCESS | 1968 | 05:37:31 INFO - PROCESS | 1968 | __start_tp_report 05:37:31 INFO - PROCESS | 1968 | _x_x_mozilla_page_load 05:37:31 INFO - PROCESS | 1968 | _x_x_mozilla_page_load_details 05:37:31 INFO - PROCESS | 1968 | |i|pagename|runs| 05:37:31 INFO - PROCESS | 1968 | |0;dojo.html;3413.1808365992156;3424.623291437943;3433.17603775094;3464.774789638672;3489.0753950203184;5681.431856814316;5700.173474846145;5708.286464191976;5763.625364351315;5817.040188368914;5684.403137670547;5695.14572814077;5782.624129431584;5821.854453638651;5834.183214349994;5137.537621614054;5186.1442861927735;5227.895442091156;5247.449017853128;5329.706866122362;5320.414754377015;5334.546563542103;5357.678539287635;5363.54409875161;5393.217983392406;5155.432902380735;5161.148410512242;5190.948090519122;5216.608754343401;5223.373195216579;5204.375474942994;5209.453007434241;5214.139666954948;5214.165733482625;5335.279737235463;5188.6367954243215;5218.7912483500995;5232.189010703347;5301.787928482856;5314.681119132864;5568.972155139239;5625.943740562583;5712.485876271114;5734.225879506297;5808.8838223235725;6262.530310226751;6291.465225455841;6318.304986451487;6318.304986451532;6330.9683451582905;6266.216722909637;6285.2457705075685;6296.370362963713;6304.747810087591;6325.683715814191;5753.9642864284415;5817.7091145442555;5884.823455296348;5994.790182343597;5994.880102397971;6298.937010629927;6306.873862522724;6313.526485513605;6324.588901721373;6330.493560515148;6205.906911396332;6206;6215.689215539205;6220.03589443637;6224.626522408579;5242.266082748418;6127.0062805303205;6183.536234782409;6279;6321.55749097568;6182.443580077815;6196.063947133313;6249.593776404519;6264.248290205204;6268.937310626927;6218.00511918091;6282.623042617458;6300.0234963580715;6303.149074874833;6325.746970121236;4919.680220785637;4948.727819969864;4964.106460837084;4995.575376093;4999.250112483084;4522.98232897602;4525.072360166224;4550.49944506104;4558.270676691714;4563.38394316763;5696.8860622787315;5760.683162426107;5760.913586296209;5780.653160810365;5785.27684039495;6218.906716399258;6254.655993920287;6275.466585340298;6283.088952102011;6298.842528936813;5777.6822274775295;5779.88440231193;5780.595358324961;5794.62334948227;5825.475707186373;5368.9731551342775;5417.783288668409;5432.945670543244;5479.808206712746;5499.312585926759;6294.402031806971;6351.174347334817;6368.42684158428;6371.362863713592;6373.490120790233;6278.591891527036;6302;6305.936940630535;6327.715252813727;6334.936650633619;6206.441420272197;6228.377162283917;6273.52948528853;6372.426481616677;6374.58565193261;6282.151909492343;6283.246010478589;6299.370062993664;6305.936940630535;6311.27420346666;6275.529335299962;6296;6303.590266632654;6321.431071203614;6340.904886426615;6019.097135429721;6052.122442245762;6125.296497837281;6131.509479241561;6197.070439433941;6011.387384525415;6146.832101900624;6183.165272688129;6189.535784816247;6198.504119670506;6048.153258543719;6049.456788531754;6065.787697430569;6069.180660610761;6109.6639684817765;6038.51691864641;6120.294761999531;6140.907886381798;6146.6004709693725;6230.470410015199;6066.757329706762;6090.482309003607;6107.782641825184;6114.877702446015;6130.9883869161085 05:37:31 INFO - PROCESS | 1968 | |1;ext.html;476.3735687570844;503.7959626351329;506.94170170430414;508.66173994293797;518.1010946008674;29818;30428.69571304289;41915.58084419155;41932.790336048354;42257.57742422575;41269.79365103178;43534;43613;43707.3443898341;43762;9155.816883662339;9251.30615203859;9341.439513629204;9349.392289501195;9375.437473751563;8007.639656215484;9613.375130616523;9631.855522167172;9639.469829159394;9669.758256043584;9484.66803661872;9607.711768646952;9614.038596140364;9682.37064590803;9701.466419346933;9468.431894086378;9474.431534107976;9570.042995700443;9610.942796292436;9665.226781857435;8184.754457366289;9429;9478;9515.23878089751;9559.139677429064;39795.40306895399;39821.60178398208;40181.598184018076;40199.397009044886;40209.59790402088;3359.2105855124028;3655;3677.3196958562485;3680.3191409589313;3957.0503079261057;28319.71680283212;28654.14037578876;28966.275843103882;29249.853750731323;29295;2683.543797554401;2687.2610032241096;2703.540398132322;2706.3910620110514;2788.6374771279807;506423.1467269299;511635;521007;521146.9999999962;522587;59011.704941475014;59300.11049834256;59334.70332648309;59357;59483.70258148682;34463.310733785685;34913.65086349104;35289.64710352915;36173;36423.4536481953;16633;16861.578460538585;16861.662766744597;17043.659126817394;17077.06076165798;12986.805197922038;13073;13091.672708182181;13092.803607945889;13096.476140954446;19323.227070917004;19666.213351466067;19892.701609475578;19924.800751992294;19933.20267189325;6497.155369801895;7274.59989700561;7869.527828330319;7872.701004334221;7981.880271795928;893.2228960804013;920.8342498350228;1044.1831213688129;1057.624543287122;1064.356064580933;24160.637590436156;28945.42109157805;28957.696038200655;29227.85386073056;29433.822647094305;983.4984158079288;1069.25152393325;1071.6356438810842;1118.7370967822555;1152.6714886257375;13493.460261589427;14401.982483400207;14621.78067328991;14785.26073696311;15024.399024038916;600609;607196;609380;611632;613698;57057;58955.70522147362;59067.113993290135;59191.70404147952;59220.40779592235;1302.433441452971;1343.536479914428;1345.7443085813663;1458.0449805377496;1492.5746162343896;24150.39624009414;28568.714312857435;29314.267143321587;30366.392672146874;31040.22399440032;53682.463175367746;54465.45534544604;54473.45526544842;54510.18234726562;54916.45083549113;536766.3161684166;600055;606757;607862;609343;22303.330900073022;24788.38029049288;24916.12793552217;25346.239612811645;25431.36421589475;44197.55802441934;44896;45566.54433455623;45955.54044459513;46385.6084317471;36689.26621467609;37086.258274834894;37212.44181337228;37568.248635027696;37845.810770945965;34410;36900;36950.55218386209;37024.25951481009;37807.86576402713;29001.85499072491;30319.93880214182;31973.680263198;36027.45958810672;37086.258274834894;28438.314574199052;30399.78400864029;31948;32211;32217;24053;24247.878760606083;24303.756962430856;24657.137000204908;24662.630060549465 05:37:31 INFO - PROCESS | 1968 | |2;jquery.html;365.48466662006575;371.5912496254121;375.27197237359525;383.6642937429749;393.8030984507746;19762.604747905032;20927.37217883466;21550.56898862022;21763.67354489684;21802;17683.55791105223;21001.57996840066;21114.894425527888;21534.676979845313;22119.225827096056;5586.692731899744;5819.418058194178;6061.212042434499;6067.605605635642;6075.483583895374;5857;5935.584509084366;5958.314793798703;5963.493103086243;6025.276966763994;5778.913316300259;5814.011618024925;5815.185873977646;5906.05503119501;5913.793017244398;5875.177475153482;5936.139259807325;5962.195103661015;5962.552808539377;5976.970115149418;4455.331700244957;5056.570191533724;5082.491750824888;5123.846284611468;5125.103106956268;13670.931645341809;22881.97031133603;24659.876700616383;26066.86966565174;26319.736802632113;2167.4473009382664;2174.913003479859;2548.210054882992;2709.9322516937236;2716.7283271672873;10049.346792458467;10329.328593641465;10459.529321180566;11512.942435287854;15406.845931540653;1705.2156008236227;1705.8294170582967;1785.0896043018024;1816.6820806358967;1902.657521646103;120714;122243;123936.9999999991;125675.74324256823;128588;9672.177864881423;9859.014098590083;10936.507857146447;11616.709582260512;11644.53421863116;14617.707645847237;14735.48425805113;14839.777403338958;14856.960012799;14885.627859303388;8405.915940840636;8425.283850872622;8621.482711037357;8847.20375166238;8850.159234872677;8649.827003459895;8651.870221946676;8660.913390866137;8669.393142480092;8819.867701984474;3882.9223415532097;4513.277875539898;4570.840020599329;4603.700759450625;4648.930266046012;1905.637928793525;1916.8307332527147;1921.2507122222344;1930.1700268884515;1946.4647222013775;819.9791259881486;876.3339861705023;897.6409436225562;914.4741773480275;939.6476321379482;20395.8980205098;20556.38330850047;20918.89540552318;21070.894645526674;21364.679529807065;902.9097090290918;936.4100616611491;981.4798156976814;1016.6797458800454;1044.529961517305;10547.156227501935;11054.336739795637;11095.334279943228;11126;11132.10943124548;595553;597806.0109699423;606099;608152;616443;71168.64415677992;107107;122241;122279;126442;921.2077613252598;935.1443429262284;937.1331518345503;959.6641175588628;982.8771403574553;17978.2808687651;18357.082145892386;18357.082145892386;18570.66210953005;18571.44285671432;40172.39310427667;42398.788006059774;52266.738666307945;55005.72497137489;55444.44555554554;73396.63301683457;74447.62776186084;74973.62513187618;75253.97968162425;77758.6112069436;7946.841063178588;8007.879881801894;8008.879866801886;8195.71315003972;8224.835503289782;11012.5044373005;12301.630951071482;12334.383280836102;12768.872311276768;12797.104202705721;10776.67669969902;10822.56709731602;10886.455677215949;11016.339019658846;11016.614418495314;10578.994995475417;10593.152547796311;10762.46187690628;10782.137429005505;10927.289726167774;9184.357095003286;11000.284981476178;11350.659480215607;11451.942740286246;11460.312381257152;7920.762377128695;7961.203879611992;8145.389095817719;8148.185181481805;8163.469374490639;9093.22707569864;9878.506074696093;10955.945220273848;11197.160212983896;11267.26762760418 05:37:31 INFO - PROCESS | 1968 | |3;mootools.html;1401.096292891086;1486.0934829753849;1542.2520077762285;1803.3958623861006;1968.2422267427046;7948;8553.786155346119;8755.781105472366;8835;8843.778905527364;8508.829823403543;8625.22372986431;8634.91365086349;8638.827223455542;8639.136086391374;2344.402177444749;2378.227076200233;2389.6654468374354;2393.0547433763654;2394.2099107294553;2233.530958498717;2345.4722687395283;2377.0967032527615;2384.308550520347;2384.678068460753;2355.6113241315247;2370.3718514593647;2379.8334116611845;2386.892589833453;2388.725296590897;2331.895064722092;2337.649352597107;2355.740868504463;2362.173239366217;2368.6920700309024;2326.3835083702834;2349.8707571083582;2352.4942137440535;2356.2813341930705;2357.6817129687443;8610.182032706884;8727.432716873383;8808.691695790616;8812.955935220347;8857.557122143868;5258.6318957672975;5465.453454654543;5475.06923822951;5478.04134276504;5502.752376143083;2891.465078960378;3412.0787387405508;3494.091536200581;3500.1774582973235;3574.1422058705725;1010.8332125199407;1027.6043723166636;1028.4395004722444;1029.917606591471;1040.5941682743735;11598;11723.355215463145;11731;11762.353070581115;11766.294022358601;4209.34755112958;4550.795214215302;4592.5407459254475;4647.604953578916;4653.790579423866;7981.600919953979;9262.397944133745;9268.443893366419;9277.768055798659;9281.071892810665;5233.842984710464;5261.579073674096;5291.629585928948;5292.788288468495;5294.205869119586;4147.688923330762;4406.228909940747;4588.518205588432;4732.574068333866;4933.062718083553;3334.2997970425995;3737.3459644562636;3760.4359346098304;3840.7695538267794;3859.729818912649;1016.5273147986222;1156.3351073132983;1163.6450882480756;1170.1633332167542;1180.1090176916546;625.4871005775311;721.1850608812009;770.4183341577185;904.5658084119659;930.0141849639404;5579.776808927679;5868.970655146697;5941.494972927263;5963.4334738199805;6131.651036771903;919.3977944446399;919.7424721078108;943.3585162089845;960.9327347085637;985.3841349156777;3681.5029970954115;3822.5604055533413;3880.0881792778673;3938.2517321708783;4030.7984600769846;10403.635872744419;10603.575856965635;10780.191485638608;10804.189685773441;10828.837567436338;10580.523876425425;10677.252592318619;10754.139668826476;10780.946095269474;10811.405372704578;7836.568988705676;8138.755837324889;8284.25441710237;8313.293370063491;8331.166883311742;5806.709664516842;5995.310539288038;6041.78853740117;6057.273127224761;6099.146119543356;9611.327207095435;10000.249981251582;10023.148032417035;10191.898081019095;10199.490025498844;9915.603375865174;10042.397456152654;10185.74535636615;10288.845667315147;10344.27590068688;7347.669354878936;7540.622968851426;7600.543967361976;7611;7617.428692847948;6821;8215.671373145246;8306.501609903426;8311.833763324821;8317.875231871647;7235.927640723736;7334.96332518334;7396.223396543285;7553.660085296285;7559.508631938907;7227.38567221771;7243.674034668347;7308.269173082649;7372.152202496782;7374.004509391163;6808.557443766337;6889.448844092361;6952.721891124501;6953.895691564529;6962;4741.50214227515;4925.729084900365;4943.406791185081;4981.925271121008;4987.551120399182;4842.273658951185;4882.658213924991;4884.609231261562;4947.332110165082;4968.080905032581 05:37:31 INFO - PROCESS | 1968 | |4;prototype.html;299.344435685848;306.0909099973079;310.7746883509455;311.6400557356254;315.06425915032344;4325.5674432556725;4350.673699472541;4918.7457198414395;5162.174052151657;5164.612654050947;5081.450157701899;5121.3342265505435;5165.509276618715;5165.974170129144;5198.142306519419;3412.4132278490497;3585.856565737374;3742.307673080475;3929.646331830135;3958.188571342868;3276.5249038889347;3847.980760096196;3902.317094508464;3922.5096862892137;3935.9212815743667;3409.5226668266464;3831.578526362098;3846.115393459506;3863.265979463893;3886.1839013807135;3373.9325213495717;3493.6681015303507;3706.332860085197;3729.3473642112795;3866.130120722843;2871.655401351851;3163.272447337102;3188.537662039015;3420.521127042216;3485.128717820545;4088.5707000764787;4540.138543197307;4907.288443175756;4947.727874966876;4956.233262720322;3916.784576848272;4169.833206671729;4356.756270520685;4503.684742068057;4525.773711314454;4246.681498887565;4646.001109761418;5288.9735551322;5367.684917195312;5379.757910894019;3384.678455546744;3742.6631603155847;3843.481130047464;3845.057960799622;3880.4373365862075;5074.416442109168;5359.507828859599;5725.971370143123;5734.684592347419;5745.253117094792;3876.1588245583685;3885.2618002579984;3895.3900886406177;3895.8636447724193;3911.2959667260166;3976.661983731415;3976.721629485966;3977.482927219443;3984.8406063757125;3995.340768773178;4158.750474971512;4225.091605304874;4498.617617502484;4511.255642818898;4553.225951588238;5245.449227831099;5440.537554307849;5468.890622187613;5473.8357849264585;5474.479924407175;1155.5724381978553;1177.682025853032;1339.1563315111418;1355.1327150623608;1358.9660258493616;697.7906628011677;826.3843436639622;833.9666413343398;836.7824365664849;837.7319257837433;4239.554846741109;4249.893752656209;4681.836135735233;4898.387701537308;4966.528179822911;4687.531246875285;5203.297554830126;5543.450012993987;5568.387477377485;5600.831975040755;4341.761203133858;4650.720956742539;4996.650234483552;4999.700017998932;5009.373828271466;2518.9370265743505;2694.770944469703;2732.303262668007;2871;2910.5052141136057;5181.559567436735;5412.052890744104;5544.750486228129;5750.281214848144;5816.563757718188;4626.86119416418;5387.030334539821;5632.436756324417;5667.574931880125;5674.687892165888;5037.168867136881;5098.566621837185;5391.433899440581;5472.93277810839;5692.089265717465;4922.901541969069;5067.442581315979;5230.541645426643;5474.835754927359;5532.30846144232;4826.565609095198;5019.7490125494305;5401.297831281908;5468.945310546844;5482.862928426821;5710.200571920018;5723.399043100581;5737.627054241461;5745.236869850255;5773.91339129905;3261.3966416213075;3558.9644103559667;3823.2735780201674;3846.249981253629;3857.0164608024775;4197.706160568731;5460.96241714073;5569.19246709233;5574.5261652758345;5590.357108932525;5391.838244852661;5398.892022159614;5402.6758394496455;5412.404635490014;5423.071541367617;5393.568514518751;5440.075187218262;5455.536279416294;5465.070937940639;5491.615586908878;5112.926285479942;5136.717480538457;5370.892582148414;5382.3541175059245;5386.865328366822;4972.502749725143;5160.561352285098;5302.7083510405755;5391.32608423947;5399.811006614749;4890.853274401774;4891.114242596885;5238.947610523846;5328.387235467971;5345.118055520951 05:37:31 INFO - PROCESS | 1968 | |5;yui.html;675.2066322071568;705.4567982653357;724.963302477457;749.6514120933768;759.7720683794867;14642.04826686266;15069.171195584238;15077.170755608451;15343.769843452357;15357.92321038396;14155;14182;14183.149011059319;14200.14799112054;14205.360758765855;700.0654126740782;724.69562783631;753.5441058159817;883.3110174064224;907.3421769217331;854.7264875239925;893.3880292000001;900.2707806676581;902.5487256371814;903.1149473515958;947.2090804178484;959.8320293948531;960.6733710538416;964.3683387381277;964.9179819715332;923.8198551282503;954.6467806911418;954.7947191353858;955.1260596554145;956.297121615612;541.1503938816061;675.6216518749516;680.6052489556095;702.1503980183984;705.2277755857328;14531.854681453156;14662.926685366612;14748.336324865406;14765.483208087773;14773.113613183135;12060.517579296906;12102.878971210263;12161.209521381083;12275.079369047357;12312.938435307855;10566.947165264124;10644.840327395095;11070.889291107067;11131.109511239081;11280.984711376015;3898.653001902858;3936.3111455495173;4080.8367665293354;4117.691173162025;4228.703990720651;75529.62235188844;77690.6115469419;78383;78682;78751;9348.158665720119;9395.812083758286;9451.243900487945;9747.853782193273;9836;21522.56954860925;21554.892225538773;21601.02795374212;21677.56644867094;21978.89010554937;6980.197277313174;6985.79042628722;7012.228654847962;7145.6784444700115;7271.600061996641;3726.4410338449447;3776.962230377716;3819.3698039823116;3826.598207188261;3828.349180639298;3282.2122690554575;3304.8347582620772;3372.1906742381652;3376.2909788944107;3376.459766437358;605.9606125601822;609.4971648390095;617.6417677746896;620.7423919073534;628.9465395441347;496.26800469307483;519.0889988070987;533.757140501071;557.90515612346;575.3096284458667;7591.8861217081785;7600.049993750781;7639.579823109671;7785.571793551409;8040.35677145827;455.3238440915238;460.30723760739875;483.77020915065765;487.4443134826301;554.8002719021149;3691.741578089508;4117.794110294473;4173.5409104998425;4194.2869712149;4207.726497777696;73704.15739606552;93840.53079734558;95802.52098739462;96304;98612.50693746486;85140.57429712811;85268;85653;87834.68247976285;87881.56059219791;77067.45865082779;78025;78134.43731125345;78216.21783782203;78663;7911.351372916568;8004.319632831041;8268.09051004377;8296.460730052528;8574.185452382013;40470.5835295775;40792;40795;41127.76616701504;41967.16065678608;57851.13223301738;62568;62844.68577657083;63351;63667;11329.660110196708;11331.716707082389;11364.715882102682;11757.647270581896;11768.058555315725;11563.415341244241;11805.114616403633;11807.291562506278;11831.585894493652;12222.755544888874;7977.521348719096;10090.646827361006;11148.108151347711;11351.14866385008;11861.406929653655;11280.09759219244;11282.379469129277;11313.71715707114;11331.603393881174;11411.60059397917;9058.54707264621;10975.670729878117;10995.725106872393;11202.775944481227;11242.718932026437;8847.159519845605;9134.36059475857;9474.52627368615;9645.974345387547;9835.803283934138;8849.734507964771;9188.219001384958;9291.442513449214;9332.95333523328;9387.765305867408 05:37:31 INFO - PROCESS | 1968 | __end_tp_report 05:37:31 INFO - PROCESS | 1968 | __start_cc_report 05:37:31 INFO - PROCESS | 1968 | _x_x_mozilla_cycle_collect,1257 05:37:31 INFO - PROCESS | 1968 | __end_cc_report 05:37:31 INFO - PROCESS | 1968 | __startTimestamp1463834251169__endTimestamp 05:37:31 INFO - PROCESS | 1968 | 05:37:31 INFO - PROCESS | 1968 | ------- Summary: start ------- 05:37:31 INFO - PROCESS | 1968 | Number of tests: 6 05:37:31 INFO - PROCESS | 1968 | 05:37:31 INFO - PROCESS | 1968 | [#0] dojo.html Cycles:165 Average:5797.98 Median:6065.79 stddev:633.19 (10.4%) stddev-sans-first:606.87 05:37:31 INFO - PROCESS | 1968 | Values: 3413.2 3424.6 3433.2 3464.8 3489.1 5681.4 5700.2 5708.3 5763.6 5817.0 5684.4 5695.1 5782.6 5821.9 5834.2 5137.5 5186.1 5227.9 5247.4 5329.7 5320.4 5334.5 5357.7 5363.5 5393.2 5155.4 5161.1 5190.9 5216.6 5223.4 5204.4 5209.5 5214.1 5214.2 5335.3 5188.6 5218.8 5232.2 5301.8 5314.7 5569.0 5625.9 5712.5 5734.2 5808.9 6262.5 6291.5 6318.3 6318.3 6331.0 6266.2 6285.2 6296.4 6304.7 6325.7 5754.0 5817.7 5884.8 5994.8 5994.9 6298.9 6306.9 6313.5 6324.6 6330.5 6205.9 6206.0 6215.7 6220.0 6224.6 5242.3 6127.0 6183.5 6279.0 6321.6 6182.4 6196.1 6249.6 6264.2 6268.9 6218.0 6282.6 6300.0 6303.1 6325.7 4919.7 4948.7 4964.1 4995.6 4999.3 4523.0 4525.1 4550.5 4558.3 4563.4 5696.9 5760.7 5760.9 5780.7 5785.3 6218.9 6254.7 6275.5 6283.1 6298.8 5777.7 5779.9 5780.6 5794.6 5825.5 5369.0 5417.8 5432.9 5479.8 5499.3 6294.4 6351.2 6368.4 6371.4 6373.5 6278.6 6302.0 6305.9 6327.7 6334.9 6206.4 6228.4 6273.5 6372.4 6374.6 6282.2 6283.2 6299.4 6305.9 6311.3 6275.5 6296.0 6303.6 6321.4 6340.9 6019.1 6052.1 6125.3 6131.5 6197.1 6011.4 6146.8 6183.2 6189.5 6198.5 6048.2 6049.5 6065.8 6069.2 6109.7 6038.5 6120.3 6140.9 6146.6 6230.5 6066.8 6090.5 6107.8 6114.9 6131.0 05:37:31 INFO - PROCESS | 1968 | 05:37:31 INFO - PROCESS | 1968 | [#1] ext.html Cycles:180 Average:69236.14 Median:37688.06 stddev:153560.13 (407.5%) stddev-sans-first:153904.12 05:37:31 INFO - PROCESS | 1968 | Values: 476.4 503.8 506.9 508.7 518.1 29818.0 30428.7 41915.6 41932.8 42257.6 41269.8 43534.0 43613.0 43707.3 43762.0 9155.8 9251.3 9341.4 9349.4 9375.4 8007.6 9613.4 9631.9 9639.5 9669.8 9484.7 9607.7 9614.0 9682.4 9701.5 9468.4 9474.4 9570.0 9610.9 9665.2 8184.8 9429.0 9478.0 9515.2 9559.1 39795.4 39821.6 40181.6 40199.4 40209.6 3359.2 3655.0 3677.3 3680.3 3957.1 28319.7 28654.1 28966.3 29249.9 29295.0 2683.5 2687.3 2703.5 2706.4 2788.6 506423.1 511635.0 521007.0 521147.0 522587.0 59011.7 59300.1 59334.7 59357.0 59483.7 34463.3 34913.7 35289.6 36173.0 36423.5 16633.0 16861.6 16861.7 17043.7 17077.1 12986.8 13073.0 13091.7 13092.8 13096.5 19323.2 19666.2 19892.7 19924.8 19933.2 6497.2 7274.6 7869.5 7872.7 7981.9 893.2 920.8 1044.2 1057.6 1064.4 24160.6 28945.4 28957.7 29227.9 29433.8 983.5 1069.3 1071.6 1118.7 1152.7 13493.5 14402.0 14621.8 14785.3 15024.4 600609.0 607196.0 609380.0 611632.0 613698.0 57057.0 58955.7 59067.1 59191.7 59220.4 1302.4 1343.5 1345.7 1458.0 1492.6 24150.4 28568.7 29314.3 30366.4 31040.2 53682.5 54465.5 54473.5 54510.2 54916.5 536766.3 600055.0 606757.0 607862.0 609343.0 22303.3 24788.4 24916.1 25346.2 25431.4 44197.6 44896.0 45566.5 45955.5 46385.6 36689.3 37086.3 37212.4 37568.2 37845.8 34410.0 36900.0 36950.6 37024.3 37807.9 29001.9 30319.9 31973.7 36027.5 37086.3 28438.3 30399.8 31948.0 32211.0 32217.0 24053.0 24247.9 24303.8 24657.1 24662.6 05:37:31 INFO - PROCESS | 1968 | 05:37:31 INFO - PROCESS | 1968 | [#2] jquery.html Cycles:180 Average:34614.72 Median:1541.11 stddev:100643.37 (6530.6%) stddev-sans-first:100892.84 05:37:31 INFO - PROCESS | 1968 | Values: 365.5 371.6 375.3 383.7 393.8 19762.6 20927.4 21550.6 21763.7 21802.0 17683.6 21001.6 21114.9 21534.7 22119.2 5586.7 5819.4 6061.2 6067.6 6075.5 5857.0 5935.6 5958.3 5963.5 6025.3 5778.9 5814.0 5815.2 5906.1 5913.8 5875.2 5936.1 5962.2 5962.6 5977.0 4455.3 5056.6 5082.5 5123.8 5125.1 13670.9 22882.0 24659.9 26066.9 26319.7 2167.4 2174.9 2548.2 2709.9 2716.7 10049.3 10329.3 10459.5 11512.9 15406.8 1705.2 1705.8 1785.1 1816.7 1902.7 120714.0 122243.0 123937.0 125675.7 128588.0 9672.2 9859.0 10936.5 11616.7 11644.5 14617.7 14735.5 14839.8 14857.0 14885.6 8405.9 8425.3 8621.5 8847.2 8850.2 8649.8 8651.9 8660.9 8669.4 8819.9 3882.9 4513.3 4570.8 4603.7 4648.9 1905.6 1916.8 1921.3 1930.2 1946.5 820.0 876.3 897.6 914.5 939.6 20395.9 20556.4 20918.9 21070.9 21364.7 902.9 936.4 981.5 1016.7 1044.5 10547.2 11054.3 11095.3 11126.0 11132.1 595553.0 597806.0 606099.0 608152.0 616443.0 71168.6 107107.0 122241.0 122279.0 126442.0 921.2 935.1 937.1 959.7 982.9 17978.3 18357.1 18357.1 18570.7 18571.4 40172.4 42398.8 52266.7 55005.7 55444.4 73396.6 74447.6 74973.6 75254.0 77758.6 7946.8 8007.9 8008.9 8195.7 8224.8 11012.5 12301.6 12334.4 12768.9 12797.1 10776.7 10822.6 10886.5 11016.3 11016.6 10579.0 10593.2 10762.5 10782.1 10927.3 9184.4 11000.3 11350.7 11451.9 11460.3 7920.8 7961.2 8145.4 8148.2 8163.5 9093.2 9878.5 10955.9 11197.2 11267.3 05:37:31 INFO - PROCESS | 1968 | 05:37:31 INFO - PROCESS | 1968 | [#3] mootools.html Cycles:180 Average:5650.68 Median:4905.17 stddev:3186.05 (65.0%) stddev-sans-first:3178.98 05:37:31 INFO - PROCESS | 1968 | Values: 1401.1 1486.1 1542.3 1803.4 1968.2 7948.0 8553.8 8755.8 8835.0 8843.8 8508.8 8625.2 8634.9 8638.8 8639.1 2344.4 2378.2 2389.7 2393.1 2394.2 2233.5 2345.5 2377.1 2384.3 2384.7 2355.6 2370.4 2379.8 2386.9 2388.7 2331.9 2337.6 2355.7 2362.2 2368.7 2326.4 2349.9 2352.5 2356.3 2357.7 8610.2 8727.4 8808.7 8813.0 8857.6 5258.6 5465.5 5475.1 5478.0 5502.8 2891.5 3412.1 3494.1 3500.2 3574.1 1010.8 1027.6 1028.4 1029.9 1040.6 11598.0 11723.4 11731.0 11762.4 11766.3 4209.3 4550.8 4592.5 4647.6 4653.8 7981.6 9262.4 9268.4 9277.8 9281.1 5233.8 5261.6 5291.6 5292.8 5294.2 4147.7 4406.2 4588.5 4732.6 4933.1 3334.3 3737.3 3760.4 3840.8 3859.7 1016.5 1156.3 1163.6 1170.2 1180.1 625.5 721.2 770.4 904.6 930.0 5579.8 5869.0 5941.5 5963.4 6131.7 919.4 919.7 943.4 960.9 985.4 3681.5 3822.6 3880.1 3938.3 4030.8 10403.6 10603.6 10780.2 10804.2 10828.8 10580.5 10677.3 10754.1 10780.9 10811.4 7836.6 8138.8 8284.3 8313.3 8331.2 5806.7 5995.3 6041.8 6057.3 6099.1 9611.3 10000.2 10023.1 10191.9 10199.5 9915.6 10042.4 10185.7 10288.8 10344.3 7347.7 7540.6 7600.5 7611.0 7617.4 6821.0 8215.7 8306.5 8311.8 8317.9 7235.9 7335.0 7396.2 7553.7 7559.5 7227.4 7243.7 7308.3 7372.2 7374.0 6808.6 6889.4 6952.7 6953.9 6962.0 4741.5 4925.7 4943.4 4981.9 4987.6 4842.3 4882.7 4884.6 4947.3 4968.1 05:37:31 INFO - PROCESS | 1968 | 05:37:31 INFO - PROCESS | 1968 | [#4] prototype.html Cycles:180 Average:4355.91 Median:4935.31 stddev:1342.47 (27.2%) stddev-sans-first:1311.26 05:37:31 INFO - PROCESS | 1968 | Values: 299.3 306.1 310.8 311.6 315.1 4325.6 4350.7 4918.7 5162.2 5164.6 5081.5 5121.3 5165.5 5166.0 5198.1 3412.4 3585.9 3742.3 3929.6 3958.2 3276.5 3848.0 3902.3 3922.5 3935.9 3409.5 3831.6 3846.1 3863.3 3886.2 3373.9 3493.7 3706.3 3729.3 3866.1 2871.7 3163.3 3188.5 3420.5 3485.1 4088.6 4540.1 4907.3 4947.7 4956.2 3916.8 4169.8 4356.8 4503.7 4525.8 4246.7 4646.0 5289.0 5367.7 5379.8 3384.7 3742.7 3843.5 3845.1 3880.4 5074.4 5359.5 5726.0 5734.7 5745.3 3876.2 3885.3 3895.4 3895.9 3911.3 3976.7 3976.7 3977.5 3984.8 3995.3 4158.8 4225.1 4498.6 4511.3 4553.2 5245.4 5440.5 5468.9 5473.8 5474.5 1155.6 1177.7 1339.2 1355.1 1359.0 697.8 826.4 834.0 836.8 837.7 4239.6 4249.9 4681.8 4898.4 4966.5 4687.5 5203.3 5543.5 5568.4 5600.8 4341.8 4650.7 4996.7 4999.7 5009.4 2518.9 2694.8 2732.3 2871.0 2910.5 5181.6 5412.1 5544.8 5750.3 5816.6 4626.9 5387.0 5632.4 5667.6 5674.7 5037.2 5098.6 5391.4 5472.9 5692.1 4922.9 5067.4 5230.5 5474.8 5532.3 4826.6 5019.7 5401.3 5468.9 5482.9 5710.2 5723.4 5737.6 5745.2 5773.9 3261.4 3559.0 3823.3 3846.2 3857.0 4197.7 5461.0 5569.2 5574.5 5590.4 5391.8 5398.9 5402.7 5412.4 5423.1 5393.6 5440.1 5455.5 5465.1 5491.6 5112.9 5136.7 5370.9 5382.4 5386.9 4972.5 5160.6 5302.7 5391.3 5399.8 4890.9 4891.1 5238.9 5328.4 5345.1 05:37:31 INFO - PROCESS | 1968 | 05:37:31 INFO - PROCESS | 1968 | [#5] yui.html Cycles:180 Average:18183.70 Median:607.73 stddev:26019.72 (4281.5%) stddev-sans-first:26059.50 05:37:31 INFO - PROCESS | 1968 | Values: 675.2 705.5 725.0 749.7 759.8 14642.0 15069.2 15077.2 15343.8 15357.9 14155.0 14182.0 14183.1 14200.1 14205.4 700.1 724.7 753.5 883.3 907.3 854.7 893.4 900.3 902.5 903.1 947.2 959.8 960.7 964.4 964.9 923.8 954.6 954.8 955.1 956.3 541.2 675.6 680.6 702.2 705.2 14531.9 14662.9 14748.3 14765.5 14773.1 12060.5 12102.9 12161.2 12275.1 12312.9 10566.9 10644.8 11070.9 11131.1 11281.0 3898.7 3936.3 4080.8 4117.7 4228.7 75529.6 77690.6 78383.0 78682.0 78751.0 9348.2 9395.8 9451.2 9747.9 9836.0 21522.6 21554.9 21601.0 21677.6 21978.9 6980.2 6985.8 7012.2 7145.7 7271.6 3726.4 3777.0 3819.4 3826.6 3828.3 3282.2 3304.8 3372.2 3376.3 3376.5 606.0 609.5 617.6 620.7 628.9 496.3 519.1 533.8 557.9 575.3 7591.9 7600.0 7639.6 7785.6 8040.4 455.3 460.3 483.8 487.4 554.8 3691.7 4117.8 4173.5 4194.3 4207.7 73704.2 93840.5 95802.5 96304.0 98612.5 85140.6 85268.0 85653.0 87834.7 87881.6 77067.5 78025.0 78134.4 78216.2 78663.0 7911.4 8004.3 8268.1 8296.5 8574.2 40470.6 40792.0 40795.0 41127.8 41967.2 57851.1 62568.0 62844.7 63351.0 63667.0 11329.7 11331.7 11364.7 11757.6 11768.1 11563.4 11805.1 11807.3 11831.6 12222.8 7977.5 10090.6 11148.1 11351.1 11861.4 11280.1 11282.4 11313.7 11331.6 11411.6 9058.5 10975.7 10995.7 11202.8 11242.7 8847.2 9134.4 9474.5 9646.0 9835.8 8849.7 9188.2 9291.4 9333.0 9387.8 05:37:31 INFO - PROCESS | 1968 | -------- Summary: end -------- 05:37:31 INFO - PROCESS | 1968 | 05:37:31 INFO - TEST-INFO | 1968: exit 0 05:37:31 INFO - TEST-OK | dromaeo_css | took 1114304ms 05:37:31 INFO - TEST-START | kraken 05:37:31 INFO - operating with platform_type : mac_ 05:37:31 INFO - Initialising browser for kraken test... 05:37:31 INFO - TEST-INFO | started process 1971 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpM83uqW/profile http://localhost:49673/getInfo.html) 05:37:34 INFO - PROCESS | 1971 | __metrics Screen width/height:1600/1200 05:37:34 INFO - PROCESS | 1971 | colorDepth:24 05:37:34 INFO - PROCESS | 1971 | Browser inner width/height: 1024/689 05:37:34 INFO - PROCESS | 1971 | __metrics 05:37:34 INFO - PROCESS | 1971 | 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" 05:37:34 ERROR - PROCESS | 1971 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:37:34 INFO - TEST-INFO | 1971: exit 0 05:37:34 INFO - Browser initialized. 05:37:34 INFO - Running cycle 1/1 for kraken test... 05:37:34 INFO - TEST-INFO | started process 1974 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpM83uqW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:37:46 INFO - PROCESS | 1974 | RSS: Main: 238907392 05:37:46 INFO - PROCESS | 1974 | 05:37:49 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49673/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49673/tests/kraken/driver.html?testName=audio-beat-detection) 05:37:50 INFO - PROCESS | 1974 | RSS: Main: 287367168 05:37:50 INFO - PROCESS | 1974 | 05:37:53 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49673/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49673/tests/kraken/driver.html?testName=audio-dft) 05:37:53 INFO - PROCESS | 1974 | RSS: Main: 295968768 05:37:53 INFO - PROCESS | 1974 | 05:37:57 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49673/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49673/tests/kraken/driver.html?testName=audio-fft) 05:37:58 INFO - PROCESS | 1974 | RSS: Main: 384008192 05:37:58 INFO - PROCESS | 1974 | 05:38:00 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49673/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49673/tests/kraken/driver.html?testName=audio-oscillator) 05:38:00 INFO - PROCESS | 1974 | RSS: Main: 284352512 05:38:00 INFO - PROCESS | 1974 | 05:38:03 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49673/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49673/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:38:03 INFO - PROCESS | 1974 | RSS: Main: 288206848 05:38:03 INFO - PROCESS | 1974 | 05:38:06 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49673/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49673/tests/kraken/driver.html?testName=imaging-darkroom) 05:38:07 INFO - PROCESS | 1974 | RSS: Main: 326541312 05:38:07 INFO - PROCESS | 1974 | 05:38:10 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49673/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49673/tests/kraken/driver.html?testName=imaging-desaturate) 05:38:11 INFO - PROCESS | 1974 | RSS: Main: 328298496 05:38:11 INFO - PROCESS | 1974 | 05:38:14 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49673/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49673/tests/kraken/driver.html?testName=json-parse-financial) 05:38:14 INFO - PROCESS | 1974 | RSS: Main: 327184384 05:38:14 INFO - PROCESS | 1974 | 05:38:16 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49673/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49673/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:38:17 INFO - PROCESS | 1974 | RSS: Main: 315588608 05:38:17 INFO - PROCESS | 1974 | 05:38:19 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49673/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49673/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:38:19 INFO - PROCESS | 1974 | RSS: Main: 322670592 05:38:19 INFO - PROCESS | 1974 | 05:38:21 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49673/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49673/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:38:22 INFO - PROCESS | 1974 | RSS: Main: 325632000 05:38:22 INFO - PROCESS | 1974 | 05:38:25 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49673/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49673/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:38:26 INFO - PROCESS | 1974 | RSS: Main: 329818112 05:38:26 INFO - PROCESS | 1974 | 05:38:29 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49673/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49673/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:38:30 INFO - PROCESS | 1974 | RSS: Main: 327737344 05:38:30 INFO - PROCESS | 1974 | 05:38:31 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:49673/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49673/tests/kraken/driver.html?testName=ai-astar) 05:38:32 INFO - PROCESS | 1974 | RSS: Main: 374456320 05:38:32 INFO - PROCESS | 1974 | 05:38:32 INFO - PROCESS | 1974 | __start_tp_report 05:38:32 INFO - PROCESS | 1974 | _x_x_mozilla_page_load 05:38:32 INFO - PROCESS | 1974 | _x_x_mozilla_page_load_details 05:38:32 INFO - PROCESS | 1974 | |i|pagename|runs| 05:38:32 INFO - PROCESS | 1974 | |0;ai-astar;113;104;113;111;113;112;112;112;111;111 05:38:32 INFO - PROCESS | 1974 | |1;audio-beat-detection;157;120;119;239;104;132;164;139;165;122 05:38:32 INFO - PROCESS | 1974 | |2;audio-dft;185;186;186;183;183;183;181;179;186;184 05:38:32 INFO - PROCESS | 1974 | |3;audio-fft;76;75;77;120;58;57;64;57;99;57 05:38:32 INFO - PROCESS | 1974 | |4;audio-oscillator;94;101;133;80;96;108;104;78;77;110 05:38:32 INFO - PROCESS | 1974 | |5;imaging-gaussian-blur;87;85;86;84;94;84;133;107;88;88 05:38:32 INFO - PROCESS | 1974 | |6;imaging-darkroom;94;94;95;94;93;94;95;94;93;94 05:38:32 INFO - PROCESS | 1974 | |7;imaging-desaturate;87;84;84;84;84;83;88;83;84;84 05:38:32 INFO - PROCESS | 1974 | |8;json-parse-financial;56;56;57;56;55;55;57;122;72;84 05:38:32 INFO - PROCESS | 1974 | |9;json-stringify-tinderbox;65;67;77;76;72;71;70;70;70;71 05:38:32 INFO - PROCESS | 1974 | |10;stanford-crypto-aes;86;87;82;82;85;84;82;86;81;83 05:38:32 INFO - PROCESS | 1974 | |11;stanford-crypto-ccm;140;138;136;137;137;136;139;139;236;131 05:38:32 INFO - PROCESS | 1974 | |12;stanford-crypto-pbkdf2;167;167;165;215;223;166;167;168;163;167 05:38:32 INFO - PROCESS | 1974 | |13;stanford-crypto-sha256-iterative;66;67;67;68;77;84;85;83;72;66 05:38:32 INFO - PROCESS | 1974 | __end_tp_report 05:38:32 INFO - PROCESS | 1974 | __start_cc_report 05:38:32 INFO - PROCESS | 1974 | _x_x_mozilla_cycle_collect,2301 05:38:32 INFO - PROCESS | 1974 | __end_cc_report 05:38:32 INFO - PROCESS | 1974 | __startTimestamp1463834312230__endTimestamp 05:38:32 INFO - PROCESS | 1974 | 05:38:32 INFO - PROCESS | 1974 | ------- Summary: start ------- 05:38:32 INFO - PROCESS | 1974 | Number of tests: 14 05:38:32 INFO - PROCESS | 1974 | 05:38:32 INFO - PROCESS | 1974 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:2.66 (2.4%) stddev-sans-first:2.74 05:38:32 INFO - PROCESS | 1974 | Values: 113.0 104.0 113.0 111.0 113.0 112.0 112.0 112.0 111.0 111.0 05:38:32 INFO - PROCESS | 1974 | 05:38:32 INFO - PROCESS | 1974 | [#1] audio-beat-detection Cycles:10 Average:146.10 Median:148.00 stddev:38.65 (26.1%) stddev-sans-first:40.79 05:38:32 INFO - PROCESS | 1974 | Values: 157.0 120.0 119.0 239.0 104.0 132.0 164.0 139.0 165.0 122.0 05:38:32 INFO - PROCESS | 1974 | 05:38:32 INFO - PROCESS | 1974 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.50 stddev:2.32 (1.3%) stddev-sans-first:2.40 05:38:32 INFO - PROCESS | 1974 | Values: 185.0 186.0 186.0 183.0 183.0 183.0 181.0 179.0 186.0 184.0 05:38:32 INFO - PROCESS | 1974 | 05:38:32 INFO - PROCESS | 1974 | [#3] audio-fft Cycles:10 Average:74.00 Median:69.50 stddev:21.02 (30.3%) stddev-sans-first:22.29 05:38:32 INFO - PROCESS | 1974 | Values: 76.0 75.0 77.0 120.0 58.0 57.0 64.0 57.0 99.0 57.0 05:38:32 INFO - PROCESS | 1974 | 05:38:32 INFO - PROCESS | 1974 | [#4] audio-oscillator Cycles:10 Average:98.10 Median:77.50 stddev:17.32 (22.3%) stddev-sans-first:18.30 05:38:32 INFO - PROCESS | 1974 | Values: 94.0 101.0 133.0 80.0 96.0 108.0 104.0 78.0 77.0 110.0 05:38:32 INFO - PROCESS | 1974 | 05:38:32 INFO - PROCESS | 1974 | [#5] imaging-gaussian-blur Cycles:10 Average:93.60 Median:86.50 stddev:15.47 (17.9%) stddev-sans-first:16.22 05:38:32 INFO - PROCESS | 1974 | Values: 87.0 85.0 86.0 84.0 94.0 84.0 133.0 107.0 88.0 88.0 05:38:32 INFO - PROCESS | 1974 | 05:38:32 INFO - PROCESS | 1974 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 05:38:32 INFO - PROCESS | 1974 | Values: 94.0 94.0 95.0 94.0 93.0 94.0 95.0 94.0 93.0 94.0 05:38:32 INFO - PROCESS | 1974 | 05:38:32 INFO - PROCESS | 1974 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:84.00 stddev:1.65 (2.0%) stddev-sans-first:1.48 05:38:32 INFO - PROCESS | 1974 | Values: 87.0 84.0 84.0 84.0 84.0 83.0 88.0 83.0 84.0 84.0 05:38:32 INFO - PROCESS | 1974 | 05:38:32 INFO - PROCESS | 1974 | [#8] json-parse-financial Cycles:10 Average:67.00 Median:56.50 stddev:21.58 (38.2%) stddev-sans-first:22.52 05:38:32 INFO - PROCESS | 1974 | Values: 56.0 56.0 57.0 56.0 55.0 55.0 57.0 122.0 72.0 84.0 05:38:32 INFO - PROCESS | 1974 | 05:38:32 INFO - PROCESS | 1974 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:3.60 (5.1%) stddev-sans-first:3.13 05:38:32 INFO - PROCESS | 1974 | Values: 65.0 67.0 77.0 76.0 72.0 71.0 70.0 70.0 70.0 71.0 05:38:32 INFO - PROCESS | 1974 | 05:38:32 INFO - PROCESS | 1974 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.50 stddev:2.10 (2.5%) stddev-sans-first:2.07 05:38:32 INFO - PROCESS | 1974 | Values: 86.0 87.0 82.0 82.0 85.0 84.0 82.0 86.0 81.0 83.0 05:38:32 INFO - PROCESS | 1974 | 05:38:32 INFO - PROCESS | 1974 | [#11] stanford-crypto-ccm Cycles:10 Average:146.90 Median:138.50 stddev:31.41 (22.7%) stddev-sans-first:33.21 05:38:32 INFO - PROCESS | 1974 | Values: 140.0 138.0 136.0 137.0 137.0 136.0 139.0 139.0 236.0 131.0 05:38:32 INFO - PROCESS | 1974 | 05:38:32 INFO - PROCESS | 1974 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.80 Median:167.00 stddev:22.36 (13.4%) stddev-sans-first:23.44 05:38:32 INFO - PROCESS | 1974 | Values: 167.0 167.0 165.0 215.0 223.0 166.0 167.0 168.0 163.0 167.0 05:38:32 INFO - PROCESS | 1974 | 05:38:32 INFO - PROCESS | 1974 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:74.50 stddev:7.99 (10.7%) stddev-sans-first:8.00 05:38:32 INFO - PROCESS | 1974 | Values: 66.0 67.0 67.0 68.0 77.0 84.0 85.0 83.0 72.0 66.0 05:38:32 INFO - PROCESS | 1974 | -------- Summary: end -------- 05:38:32 INFO - PROCESS | 1974 | 05:38:33 INFO - TEST-INFO | 1974: exit 0 05:38:33 INFO - TEST-OK | kraken | took 61273ms 05:38:33 INFO - SUITE-END | took 1175s 05:38:33 INFO - Completed test suite (00:19:35) 05:38:33 INFO - javascript benchmark 05:38:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3413.1808365992156, 3424.623291437943, 3433.17603775094, 3464.774789638672, 3489.0753950203184, 5681.431856814316, 5700.173474846145, 5708.286464191976, 5763.625364351315, 5817.040188368914, 5684.403137670547, 5695.14572814077, 5782.624129431584, 5821.854453638651, 5834.183214349994, 5137.537621614054, 5186.1442861927735, 5227.895442091156, 5247.449017853128, 5329.706866122362, 5320.414754377015, 5334.546563542103, 5357.678539287635, 5363.54409875161, 5393.217983392406, 5155.432902380735, 5161.148410512242, 5190.948090519122, 5216.608754343401, 5223.373195216579, 5204.375474942994, 5209.453007434241, 5214.139666954948, 5214.165733482625, 5335.279737235463, 5188.6367954243215, 5218.7912483500995, 5232.189010703347, 5301.787928482856, 5314.681119132864, 5568.972155139239, 5625.943740562583, 5712.485876271114, 5734.225879506297, 5808.8838223235725, 6262.530310226751, 6291.465225455841, 6318.304986451487, 6318.304986451532, 6330.9683451582905, 6266.216722909637, 6285.2457705075685, 6296.370362963713, 6304.747810087591, 6325.683715814191, 5753.9642864284415, 5817.7091145442555, 5884.823455296348, 5994.790182343597, 5994.880102397971, 6298.937010629927, 6306.873862522724, 6313.526485513605, 6324.588901721373, 6330.493560515148, 6205.906911396332, 6206.0, 6215.689215539205, 6220.03589443637, 6224.626522408579, 5242.266082748418, 6127.0062805303205, 6183.536234782409, 6279.0, 6321.55749097568, 6182.443580077815, 6196.063947133313, 6249.593776404519, 6264.248290205204, 6268.937310626927, 6218.00511918091, 6282.623042617458, 6300.0234963580715, 6303.149074874833, 6325.746970121236, 4919.680220785637, 4948.727819969864, 4964.106460837084, 4995.575376093, 4999.250112483084, 4522.98232897602, 4525.072360166224, 4550.49944506104, 4558.270676691714, 4563.38394316763, 5696.8860622787315, 5760.683162426107, 5760.913586296209, 5780.653160810365, 5785.27684039495, 6218.906716399258, 6254.655993920287, 6275.466585340298, 6283.088952102011, 6298.842528936813, 5777.6822274775295, 5779.88440231193, 5780.595358324961, 5794.62334948227, 5825.475707186373, 5368.9731551342775, 5417.783288668409, 5432.945670543244, 5479.808206712746, 5499.312585926759, 6294.402031806971, 6351.174347334817, 6368.42684158428, 6371.362863713592, 6373.490120790233, 6278.591891527036, 6302.0, 6305.936940630535, 6327.715252813727, 6334.936650633619, 6206.441420272197, 6228.377162283917, 6273.52948528853, 6372.426481616677, 6374.58565193261, 6282.151909492343, 6283.246010478589, 6299.370062993664, 6305.936940630535, 6311.27420346666, 6275.529335299962, 6296.0, 6303.590266632654, 6321.431071203614, 6340.904886426615, 6019.097135429721, 6052.122442245762, 6125.296497837281, 6131.509479241561, 6197.070439433941, 6011.387384525415, 6146.832101900624, 6183.165272688129, 6189.535784816247, 6198.504119670506, 6048.153258543719, 6049.456788531754, 6065.787697430569, 6069.180660610761, 6109.6639684817765, 6038.51691864641, 6120.294761999531, 6140.907886381798, 6146.6004709693725, 6230.470410015199, 6066.757329706762, 6090.482309003607, 6107.782641825184, 6114.877702446015, 6130.9883869161085], "value": 5757.921691924656, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.3735687570844, 503.7959626351329, 506.94170170430414, 508.66173994293797, 518.1010946008674, 29818.0, 30428.69571304289, 41915.58084419155, 41932.790336048354, 42257.57742422575, 41269.79365103178, 43534.0, 43613.0, 43707.3443898341, 43762.0, 9155.816883662339, 9251.30615203859, 9341.439513629204, 9349.392289501195, 9375.437473751563, 8007.639656215484, 9613.375130616523, 9631.855522167172, 9639.469829159394, 9669.758256043584, 9484.66803661872, 9607.711768646952, 9614.038596140364, 9682.37064590803, 9701.466419346933, 9468.431894086378, 9474.431534107976, 9570.042995700443, 9610.942796292436, 9665.226781857435, 8184.754457366289, 9429.0, 9478.0, 9515.23878089751, 9559.139677429064, 39795.40306895399, 39821.60178398208, 40181.598184018076, 40199.397009044886, 40209.59790402088, 3359.2105855124028, 3655.0, 3677.3196958562485, 3680.3191409589313, 3957.0503079261057, 28319.71680283212, 28654.14037578876, 28966.275843103882, 29249.853750731323, 29295.0, 2683.543797554401, 2687.2610032241096, 2703.540398132322, 2706.3910620110514, 2788.6374771279807, 506423.1467269299, 511635.0, 521007.0, 521146.9999999962, 522587.0, 59011.704941475014, 59300.11049834256, 59334.70332648309, 59357.0, 59483.70258148682, 34463.310733785685, 34913.65086349104, 35289.64710352915, 36173.0, 36423.4536481953, 16633.0, 16861.578460538585, 16861.662766744597, 17043.659126817394, 17077.06076165798, 12986.805197922038, 13073.0, 13091.672708182181, 13092.803607945889, 13096.476140954446, 19323.227070917004, 19666.213351466067, 19892.701609475578, 19924.800751992294, 19933.20267189325, 6497.155369801895, 7274.59989700561, 7869.527828330319, 7872.701004334221, 7981.880271795928, 893.2228960804013, 920.8342498350228, 1044.1831213688129, 1057.624543287122, 1064.356064580933, 24160.637590436156, 28945.42109157805, 28957.696038200655, 29227.85386073056, 29433.822647094305, 983.4984158079288, 1069.25152393325, 1071.6356438810842, 1118.7370967822555, 1152.6714886257375, 13493.460261589427, 14401.982483400207, 14621.78067328991, 14785.26073696311, 15024.399024038916, 600609.0, 607196.0, 609380.0, 611632.0, 613698.0, 57057.0, 58955.70522147362, 59067.113993290135, 59191.70404147952, 59220.40779592235, 1302.433441452971, 1343.536479914428, 1345.7443085813663, 1458.0449805377496, 1492.5746162343896, 24150.39624009414, 28568.714312857435, 29314.267143321587, 30366.392672146874, 31040.22399440032, 53682.463175367746, 54465.45534544604, 54473.45526544842, 54510.18234726562, 54916.45083549113, 536766.3161684166, 600055.0, 606757.0, 607862.0, 609343.0, 22303.330900073022, 24788.38029049288, 24916.12793552217, 25346.239612811645, 25431.36421589475, 44197.55802441934, 44896.0, 45566.54433455623, 45955.54044459513, 46385.6084317471, 36689.26621467609, 37086.258274834894, 37212.44181337228, 37568.248635027696, 37845.810770945965, 34410.0, 36900.0, 36950.55218386209, 37024.25951481009, 37807.86576402713, 29001.85499072491, 30319.93880214182, 31973.680263198, 36027.45958810672, 37086.258274834894, 28438.314574199052, 30399.78400864029, 31948.0, 32211.0, 32217.0, 24053.0, 24247.878760606083, 24303.756962430856, 24657.137000204908, 24662.630060549465], "value": 19285.625139521297, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.48466662006575, 371.5912496254121, 375.27197237359525, 383.6642937429749, 393.8030984507746, 19762.604747905032, 20927.37217883466, 21550.56898862022, 21763.67354489684, 21802.0, 17683.55791105223, 21001.57996840066, 21114.894425527888, 21534.676979845313, 22119.225827096056, 5586.692731899744, 5819.418058194178, 6061.212042434499, 6067.605605635642, 6075.483583895374, 5857.0, 5935.584509084366, 5958.314793798703, 5963.493103086243, 6025.276966763994, 5778.913316300259, 5814.011618024925, 5815.185873977646, 5906.05503119501, 5913.793017244398, 5875.177475153482, 5936.139259807325, 5962.195103661015, 5962.552808539377, 5976.970115149418, 4455.331700244957, 5056.570191533724, 5082.491750824888, 5123.846284611468, 5125.103106956268, 13670.931645341809, 22881.97031133603, 24659.876700616383, 26066.86966565174, 26319.736802632113, 2167.4473009382664, 2174.913003479859, 2548.210054882992, 2709.9322516937236, 2716.7283271672873, 10049.346792458467, 10329.328593641465, 10459.529321180566, 11512.942435287854, 15406.845931540653, 1705.2156008236227, 1705.8294170582967, 1785.0896043018024, 1816.6820806358967, 1902.657521646103, 120714.0, 122243.0, 123936.9999999991, 125675.74324256823, 128588.0, 9672.177864881423, 9859.014098590083, 10936.507857146447, 11616.709582260512, 11644.53421863116, 14617.707645847237, 14735.48425805113, 14839.777403338958, 14856.960012799, 14885.627859303388, 8405.915940840636, 8425.283850872622, 8621.482711037357, 8847.20375166238, 8850.159234872677, 8649.827003459895, 8651.870221946676, 8660.913390866137, 8669.393142480092, 8819.867701984474, 3882.9223415532097, 4513.277875539898, 4570.840020599329, 4603.700759450625, 4648.930266046012, 1905.637928793525, 1916.8307332527147, 1921.2507122222344, 1930.1700268884515, 1946.4647222013775, 819.9791259881486, 876.3339861705023, 897.6409436225562, 914.4741773480275, 939.6476321379482, 20395.8980205098, 20556.38330850047, 20918.89540552318, 21070.894645526674, 21364.679529807065, 902.9097090290918, 936.4100616611491, 981.4798156976814, 1016.6797458800454, 1044.529961517305, 10547.156227501935, 11054.336739795637, 11095.334279943228, 11126.0, 11132.10943124548, 595553.0, 597806.0109699423, 606099.0, 608152.0, 616443.0, 71168.64415677992, 107107.0, 122241.0, 122279.0, 126442.0, 921.2077613252598, 935.1443429262284, 937.1331518345503, 959.6641175588628, 982.8771403574553, 17978.2808687651, 18357.082145892386, 18357.082145892386, 18570.66210953005, 18571.44285671432, 40172.39310427667, 42398.788006059774, 52266.738666307945, 55005.72497137489, 55444.44555554554, 73396.63301683457, 74447.62776186084, 74973.62513187618, 75253.97968162425, 77758.6112069436, 7946.841063178588, 8007.879881801894, 8008.879866801886, 8195.71315003972, 8224.835503289782, 11012.5044373005, 12301.630951071482, 12334.383280836102, 12768.872311276768, 12797.104202705721, 10776.67669969902, 10822.56709731602, 10886.455677215949, 11016.339019658846, 11016.614418495314, 10578.994995475417, 10593.152547796311, 10762.46187690628, 10782.137429005505, 10927.289726167774, 9184.357095003286, 11000.284981476178, 11350.659480215607, 11451.942740286246, 11460.312381257152, 7920.762377128695, 7961.203879611992, 8145.389095817719, 8148.185181481805, 8163.469374490639, 9093.22707569864, 9878.506074696093, 10955.945220273848, 11197.160212983896, 11267.26762760418], "value": 9456.10339324328, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1401.096292891086, 1486.0934829753849, 1542.2520077762285, 1803.3958623861006, 1968.2422267427046, 7948.0, 8553.786155346119, 8755.781105472366, 8835.0, 8843.778905527364, 8508.829823403543, 8625.22372986431, 8634.91365086349, 8638.827223455542, 8639.136086391374, 2344.402177444749, 2378.227076200233, 2389.6654468374354, 2393.0547433763654, 2394.2099107294553, 2233.530958498717, 2345.4722687395283, 2377.0967032527615, 2384.308550520347, 2384.678068460753, 2355.6113241315247, 2370.3718514593647, 2379.8334116611845, 2386.892589833453, 2388.725296590897, 2331.895064722092, 2337.649352597107, 2355.740868504463, 2362.173239366217, 2368.6920700309024, 2326.3835083702834, 2349.8707571083582, 2352.4942137440535, 2356.2813341930705, 2357.6817129687443, 8610.182032706884, 8727.432716873383, 8808.691695790616, 8812.955935220347, 8857.557122143868, 5258.6318957672975, 5465.453454654543, 5475.06923822951, 5478.04134276504, 5502.752376143083, 2891.465078960378, 3412.0787387405508, 3494.091536200581, 3500.1774582973235, 3574.1422058705725, 1010.8332125199407, 1027.6043723166636, 1028.4395004722444, 1029.917606591471, 1040.5941682743735, 11598.0, 11723.355215463145, 11731.0, 11762.353070581115, 11766.294022358601, 4209.34755112958, 4550.795214215302, 4592.5407459254475, 4647.604953578916, 4653.790579423866, 7981.600919953979, 9262.397944133745, 9268.443893366419, 9277.768055798659, 9281.071892810665, 5233.842984710464, 5261.579073674096, 5291.629585928948, 5292.788288468495, 5294.205869119586, 4147.688923330762, 4406.228909940747, 4588.518205588432, 4732.574068333866, 4933.062718083553, 3334.2997970425995, 3737.3459644562636, 3760.4359346098304, 3840.7695538267794, 3859.729818912649, 1016.5273147986222, 1156.3351073132983, 1163.6450882480756, 1170.1633332167542, 1180.1090176916546, 625.4871005775311, 721.1850608812009, 770.4183341577185, 904.5658084119659, 930.0141849639404, 5579.776808927679, 5868.970655146697, 5941.494972927263, 5963.4334738199805, 6131.651036771903, 919.3977944446399, 919.7424721078108, 943.3585162089845, 960.9327347085637, 985.3841349156777, 3681.5029970954115, 3822.5604055533413, 3880.0881792778673, 3938.2517321708783, 4030.7984600769846, 10403.635872744419, 10603.575856965635, 10780.191485638608, 10804.189685773441, 10828.837567436338, 10580.523876425425, 10677.252592318619, 10754.139668826476, 10780.946095269474, 10811.405372704578, 7836.568988705676, 8138.755837324889, 8284.25441710237, 8313.293370063491, 8331.166883311742, 5806.709664516842, 5995.310539288038, 6041.78853740117, 6057.273127224761, 6099.146119543356, 9611.327207095435, 10000.249981251582, 10023.148032417035, 10191.898081019095, 10199.490025498844, 9915.603375865174, 10042.397456152654, 10185.74535636615, 10288.845667315147, 10344.27590068688, 7347.669354878936, 7540.622968851426, 7600.543967361976, 7611.0, 7617.428692847948, 6821.0, 8215.671373145246, 8306.501609903426, 8311.833763324821, 8317.875231871647, 7235.927640723736, 7334.96332518334, 7396.223396543285, 7553.660085296285, 7559.508631938907, 7227.38567221771, 7243.674034668347, 7308.269173082649, 7372.152202496782, 7374.004509391163, 6808.557443766337, 6889.448844092361, 6952.721891124501, 6953.895691564529, 6962.0, 4741.50214227515, 4925.729084900365, 4943.406791185081, 4981.925271121008, 4987.551120399182, 4842.273658951185, 4882.658213924991, 4884.609231261562, 4947.332110165082, 4968.080905032581], "value": 4518.184146502377, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.344435685848, 306.0909099973079, 310.7746883509455, 311.6400557356254, 315.06425915032344, 4325.5674432556725, 4350.673699472541, 4918.7457198414395, 5162.174052151657, 5164.612654050947, 5081.450157701899, 5121.3342265505435, 5165.509276618715, 5165.974170129144, 5198.142306519419, 3412.4132278490497, 3585.856565737374, 3742.307673080475, 3929.646331830135, 3958.188571342868, 3276.5249038889347, 3847.980760096196, 3902.317094508464, 3922.5096862892137, 3935.9212815743667, 3409.5226668266464, 3831.578526362098, 3846.115393459506, 3863.265979463893, 3886.1839013807135, 3373.9325213495717, 3493.6681015303507, 3706.332860085197, 3729.3473642112795, 3866.130120722843, 2871.655401351851, 3163.272447337102, 3188.537662039015, 3420.521127042216, 3485.128717820545, 4088.5707000764787, 4540.138543197307, 4907.288443175756, 4947.727874966876, 4956.233262720322, 3916.784576848272, 4169.833206671729, 4356.756270520685, 4503.684742068057, 4525.773711314454, 4246.681498887565, 4646.001109761418, 5288.9735551322, 5367.684917195312, 5379.757910894019, 3384.678455546744, 3742.6631603155847, 3843.481130047464, 3845.057960799622, 3880.4373365862075, 5074.416442109168, 5359.507828859599, 5725.971370143123, 5734.684592347419, 5745.253117094792, 3876.1588245583685, 3885.2618002579984, 3895.3900886406177, 3895.8636447724193, 3911.2959667260166, 3976.661983731415, 3976.721629485966, 3977.482927219443, 3984.8406063757125, 3995.340768773178, 4158.750474971512, 4225.091605304874, 4498.617617502484, 4511.255642818898, 4553.225951588238, 5245.449227831099, 5440.537554307849, 5468.890622187613, 5473.8357849264585, 5474.479924407175, 1155.5724381978553, 1177.682025853032, 1339.1563315111418, 1355.1327150623608, 1358.9660258493616, 697.7906628011677, 826.3843436639622, 833.9666413343398, 836.7824365664849, 837.7319257837433, 4239.554846741109, 4249.893752656209, 4681.836135735233, 4898.387701537308, 4966.528179822911, 4687.531246875285, 5203.297554830126, 5543.450012993987, 5568.387477377485, 5600.831975040755, 4341.761203133858, 4650.720956742539, 4996.650234483552, 4999.700017998932, 5009.373828271466, 2518.9370265743505, 2694.770944469703, 2732.303262668007, 2871.0, 2910.5052141136057, 5181.559567436735, 5412.052890744104, 5544.750486228129, 5750.281214848144, 5816.563757718188, 4626.86119416418, 5387.030334539821, 5632.436756324417, 5667.574931880125, 5674.687892165888, 5037.168867136881, 5098.566621837185, 5391.433899440581, 5472.93277810839, 5692.089265717465, 4922.901541969069, 5067.442581315979, 5230.541645426643, 5474.835754927359, 5532.30846144232, 4826.565609095198, 5019.7490125494305, 5401.297831281908, 5468.945310546844, 5482.862928426821, 5710.200571920018, 5723.399043100581, 5737.627054241461, 5745.236869850255, 5773.91339129905, 3261.3966416213075, 3558.9644103559667, 3823.2735780201674, 3846.249981253629, 3857.0164608024775, 4197.706160568731, 5460.96241714073, 5569.19246709233, 5574.5261652758345, 5590.357108932525, 5391.838244852661, 5398.892022159614, 5402.6758394496455, 5412.404635490014, 5423.071541367617, 5393.568514518751, 5440.075187218262, 5455.536279416294, 5465.070937940639, 5491.615586908878, 5112.926285479942, 5136.717480538457, 5370.892582148414, 5382.3541175059245, 5386.865328366822, 4972.502749725143, 5160.561352285098, 5302.7083510405755, 5391.32608423947, 5399.811006614749, 4890.853274401774, 4891.114242596885, 5238.947610523846, 5328.387235467971, 5345.118055520951], "value": 3928.4783181740677, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.2066322071568, 705.4567982653357, 724.963302477457, 749.6514120933768, 759.7720683794867, 14642.04826686266, 15069.171195584238, 15077.170755608451, 15343.769843452357, 15357.92321038396, 14155.0, 14182.0, 14183.149011059319, 14200.14799112054, 14205.360758765855, 700.0654126740782, 724.69562783631, 753.5441058159817, 883.3110174064224, 907.3421769217331, 854.7264875239925, 893.3880292000001, 900.2707806676581, 902.5487256371814, 903.1149473515958, 947.2090804178484, 959.8320293948531, 960.6733710538416, 964.3683387381277, 964.9179819715332, 923.8198551282503, 954.6467806911418, 954.7947191353858, 955.1260596554145, 956.297121615612, 541.1503938816061, 675.6216518749516, 680.6052489556095, 702.1503980183984, 705.2277755857328, 14531.854681453156, 14662.926685366612, 14748.336324865406, 14765.483208087773, 14773.113613183135, 12060.517579296906, 12102.878971210263, 12161.209521381083, 12275.079369047357, 12312.938435307855, 10566.947165264124, 10644.840327395095, 11070.889291107067, 11131.109511239081, 11280.984711376015, 3898.653001902858, 3936.3111455495173, 4080.8367665293354, 4117.691173162025, 4228.703990720651, 75529.62235188844, 77690.6115469419, 78383.0, 78682.0, 78751.0, 9348.158665720119, 9395.812083758286, 9451.243900487945, 9747.853782193273, 9836.0, 21522.56954860925, 21554.892225538773, 21601.02795374212, 21677.56644867094, 21978.89010554937, 6980.197277313174, 6985.79042628722, 7012.228654847962, 7145.6784444700115, 7271.600061996641, 3726.4410338449447, 3776.962230377716, 3819.3698039823116, 3826.598207188261, 3828.349180639298, 3282.2122690554575, 3304.8347582620772, 3372.1906742381652, 3376.2909788944107, 3376.459766437358, 605.9606125601822, 609.4971648390095, 617.6417677746896, 620.7423919073534, 628.9465395441347, 496.26800469307483, 519.0889988070987, 533.757140501071, 557.90515612346, 575.3096284458667, 7591.8861217081785, 7600.049993750781, 7639.579823109671, 7785.571793551409, 8040.35677145827, 455.3238440915238, 460.30723760739875, 483.77020915065765, 487.4443134826301, 554.8002719021149, 3691.741578089508, 4117.794110294473, 4173.5409104998425, 4194.2869712149, 4207.726497777696, 73704.15739606552, 93840.53079734558, 95802.52098739462, 96304.0, 98612.50693746486, 85140.57429712811, 85268.0, 85653.0, 87834.68247976285, 87881.56059219791, 77067.45865082779, 78025.0, 78134.43731125345, 78216.21783782203, 78663.0, 7911.351372916568, 8004.319632831041, 8268.09051004377, 8296.460730052528, 8574.185452382013, 40470.5835295775, 40792.0, 40795.0, 41127.76616701504, 41967.16065678608, 57851.13223301738, 62568.0, 62844.68577657083, 63351.0, 63667.0, 11329.660110196708, 11331.716707082389, 11364.715882102682, 11757.647270581896, 11768.058555315725, 11563.415341244241, 11805.114616403633, 11807.291562506278, 11831.585894493652, 12222.755544888874, 7977.521348719096, 10090.646827361006, 11148.108151347711, 11351.14866385008, 11861.406929653655, 11280.09759219244, 11282.379469129277, 11313.71715707114, 11331.603393881174, 11411.60059397917, 9058.54707264621, 10975.670729878117, 10995.725106872393, 11202.775944481227, 11242.718932026437, 8847.159519845605, 9134.36059475857, 9474.52627368615, 9645.974345387547, 9835.803283934138, 8849.734507964771, 9188.219001384958, 9291.442513449214, 9332.95333523328, 9387.765305867408], "value": 6759.240992983534, "unit": "score"}], "name": "dromaeo_css", "value": 7080.394301649314, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 104.0, 113.0, 111.0, 113.0, 112.0, 112.0, 112.0, 111.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 120.0, 119.0, 239.0, 104.0, 132.0, 164.0, 139.0, 165.0, 122.0], "value": 146.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 186.0, 183.0, 183.0, 183.0, 181.0, 179.0, 186.0, 184.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 120.0, 58.0, 57.0, 64.0, 57.0, 99.0, 57.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 101.0, 133.0, 80.0, 96.0, 108.0, 104.0, 78.0, 77.0, 110.0], "value": 98.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 86.0, 84.0, 94.0, 84.0, 133.0, 107.0, 88.0, 88.0], "value": 93.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 93.0, 94.0, 95.0, 94.0, 93.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 84.0, 84.0, 84.0, 83.0, 88.0, 83.0, 84.0, 84.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 57.0, 56.0, 55.0, 55.0, 57.0, 122.0, 72.0, 84.0], "value": 67.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 77.0, 76.0, 72.0, 71.0, 70.0, 70.0, 70.0, 71.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 82.0, 82.0, 85.0, 84.0, 82.0, 86.0, 81.0, 83.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 136.0, 137.0, 137.0, 136.0, 139.0, 139.0, 236.0, 131.0], "value": 146.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 165.0, 215.0, 223.0, 166.0, 167.0, 168.0, 163.0, 167.0], "value": 176.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 68.0, 77.0, 84.0, 85.0, 83.0, 72.0, 66.0], "value": 73.5, "unit": "score"}], "name": "kraken", "value": 1504.0, "alertThreshold": 2.0}]} 05:38:33 INFO - Return code: 0 05:38:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:38:33 ERROR - # TBPL SUCCESS # 05:38:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:38:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:38:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:38:33 INFO - 2016-05-21 05:38:33.555 screenresolution[1978:14697] starting screenresolution argv=screenresolution get 05:38:33 INFO - 2016-05-21 05:38:33.558 screenresolution[1978:14697] Display 0: 1600x1200x32@60 05:38:33 INFO - 2016-05-21 05:38:33.564 screenresolution[1979:14700] starting screenresolution argv=screenresolution list 05:38:33 INFO - Available Modes on Display 0 05:38:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:38:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:38:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:38:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:38:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:38:33 INFO - Intel Iris: 05:38:33 INFO - Chipset Model: Intel Iris 05:38:33 INFO - Type: GPU 05:38:33 INFO - Bus: Built-In 05:38:33 INFO - VRAM (Dynamic, Max): 1536 MB 05:38:33 INFO - Vendor: Intel (0x8086) 05:38:33 INFO - Device ID: 0x0a2e 05:38:33 INFO - Revision ID: 0x0009 05:38:33 INFO - Displays: 05:38:33 INFO - Display: 05:38:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:38:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:38:33 INFO - Display Serial Number: 1600x1200 60 05:38:33 INFO - Main Display: Yes 05:38:33 INFO - Mirror: Off 05:38:33 INFO - Online: Yes 05:38:33 INFO - Rotation: Supported 05:38:33 INFO - Return code: 0 05:38:33 INFO - Running post-action listener: _resource_record_post_action 05:38:33 INFO - ##### 05:38:33 INFO - ##### Finished run-tests step (success) 05:38:33 INFO - ##### 05:38:33 INFO - Running post-run listener: _resource_record_post_run 05:38:33 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 57344; Write bytes: 697389568; Read time: 2; Write time: 1940 05:38:33 INFO - TinderboxPrint: CPU usage
53.6% 05:38:33 INFO - TinderboxPrint: I/O read bytes / time
57,344 / 2 05:38:33 INFO - TinderboxPrint: I/O write bytes / time
697,389,568 / 1,940 05:38:33 INFO - TinderboxPrint: CPU idle
2,249.2 (46.4%) 05:38:33 INFO - TinderboxPrint: CPU system
968.9 (20.0%) 05:38:33 INFO - TinderboxPrint: CPU user
1,631.6 (33.6%) 05:38:33 INFO - TinderboxPrint: Swap in / out
395,616,256 / 0 05:38:33 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 385784320; Write bytes: 380024832; Read time: 29133; Write time: 771 05:38:33 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 57344; Write bytes: 297851392; Read time: 2; Write time: 1116 05:38:34 INFO - Running post-run listener: _upload_blobber_files 05:38:34 INFO - Blob upload gear active. 05:38:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:38:34 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:38:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:38:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:38:34 INFO - (blobuploader) - INFO - Open directory for files ... 05:38:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:38:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:38:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:35 INFO - (blobuploader) - INFO - Done attempting. 05:38:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:38:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:38:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:36 INFO - (blobuploader) - INFO - Done attempting. 05:38:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:38:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:38:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:38 INFO - (blobuploader) - INFO - Done attempting. 05:38:38 INFO - (blobuploader) - INFO - Iteration through files over. 05:38:38 INFO - Return code: 0 05:38:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:38:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:38:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc6a60b9b1b7daa78f567bcc1f01d03433614a60fea5bc474562f89ad339a02850cb3d87319fbeee3b6842a60c72dceab7311eb652a2188853776e15ce3875b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddd23e47ee4da1691bd0514e40a8ba405c58885611635290413191cf71c14414131f445d9097ba4edb7dab6ba3d3fef96c0080b9fe81a2745a8114e2ca1bd4b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8292132904336f7ceacec013cfbaadd17e91e434f93cda0da468cc173d7b2d9b4cc616e1ee0adf1036247291d3eff946a64cf540145cf82f8a195303ac6af0da"} 05:38:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:38:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:38:38 INFO - Contents: 05:38:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc6a60b9b1b7daa78f567bcc1f01d03433614a60fea5bc474562f89ad339a02850cb3d87319fbeee3b6842a60c72dceab7311eb652a2188853776e15ce3875b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddd23e47ee4da1691bd0514e40a8ba405c58885611635290413191cf71c14414131f445d9097ba4edb7dab6ba3d3fef96c0080b9fe81a2745a8114e2ca1bd4b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8292132904336f7ceacec013cfbaadd17e91e434f93cda0da468cc173d7b2d9b4cc616e1ee0adf1036247291d3eff946a64cf540145cf82f8a195303ac6af0da"} 05:38:38 INFO - Running post-run listener: copy_logs_to_upload_dir 05:38:38 INFO - Copying logs to upload dir... 05:38:38 INFO - mkdir: /builds/slave/test/build/upload/logs 05:38:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1343.172065 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 23 secs) (at 2016-05-21 05:38:39.073572) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-21 05:38:39.077246) ========= 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.OZ9lqZ7asN/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.KKpGJG9spt/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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/Firefox/sha512/cc6a60b9b1b7daa78f567bcc1f01d03433614a60fea5bc474562f89ad339a02850cb3d87319fbeee3b6842a60c72dceab7311eb652a2188853776e15ce3875b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddd23e47ee4da1691bd0514e40a8ba405c58885611635290413191cf71c14414131f445d9097ba4edb7dab6ba3d3fef96c0080b9fe81a2745a8114e2ca1bd4b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8292132904336f7ceacec013cfbaadd17e91e434f93cda0da468cc173d7b2d9b4cc616e1ee0adf1036247291d3eff946a64cf540145cf82f8a195303ac6af0da"} build_url:https://queue.taskcluster.net/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010878 build_url: 'https://queue.taskcluster.net/v1/task/H2XPdz6VQFeoaeA2NslhJQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc6a60b9b1b7daa78f567bcc1f01d03433614a60fea5bc474562f89ad339a02850cb3d87319fbeee3b6842a60c72dceab7311eb652a2188853776e15ce3875b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddd23e47ee4da1691bd0514e40a8ba405c58885611635290413191cf71c14414131f445d9097ba4edb7dab6ba3d3fef96c0080b9fe81a2745a8114e2ca1bd4b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8292132904336f7ceacec013cfbaadd17e91e434f93cda0da468cc173d7b2d9b4cc616e1ee0adf1036247291d3eff946a64cf540145cf82f8a195303ac6af0da"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-21 05:38:39.106479) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 05:38:39.106832) ========= 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.OZ9lqZ7asN/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.KKpGJG9spt/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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.005209 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 05:38:39.156006) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-21 05:38:39.156389) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-21 05:38:39.156811) ========= ========= Total master_lag: 0.21 =========