builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0215 starttime: 1488382384.46 results: success (0) buildid: 20170301072336 builduid: 7c30fda4e6d84bbdafbc45cd8658a9b4 revision: 34c6c2f302e7b48e3ad2cec575cbd34d423a9d32 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-01 07:33:04.462757) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-01 07:33:04.463308) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-01 07:33:04.463590) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-01 07:33:04.489083) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 07:33:04.489493) ========= 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.scRvxXCVV4/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.66ky427u0D/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 --2017-03-01 07:33:04-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.99M=0.001s 2017-03-01 07:33:04 (7.99 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.110302 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 07:33:04.623884) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 07:33:04.624213) ========= 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.scRvxXCVV4/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.66ky427u0D/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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.020898 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 07:33:04.673504) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-01 07:33:04.673877) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 34c6c2f302e7b48e3ad2cec575cbd34d423a9d32 --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 34c6c2f302e7b48e3ad2cec575cbd34d423a9d32 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.scRvxXCVV4/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.66ky427u0D/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 2017-03-01 07:33:04,764 truncating revision to first 12 chars 2017-03-01 07:33:04,764 Setting DEBUG logging. 2017-03-01 07:33:04,764 attempt 1/10 2017-03-01 07:33:04,764 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/34c6c2f302e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-01 07:33:05,921 unpacking tar archive at: mozilla-central-34c6c2f302e7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.413467 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-01 07:33:06.111515) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-01 07:33:06.111878) ========= script_repo_revision: 34c6c2f302e7b48e3ad2cec575cbd34d423a9d32 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-01 07:33:06.112341) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-01 07:33:06.112646) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-01 07:33:06.133054) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-03-01 07:33:06.133485) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--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.scRvxXCVV4/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.66ky427u0D/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 07:33:06 INFO - MultiFileLogger online at 20170301 07:33:06 in /builds/slave/test 07:33:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:33:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:33:06 INFO - {'append_to_log': False, 07:33:06 INFO - 'base_work_dir': '/builds/slave/test', 07:33:06 INFO - 'blob_upload_branch': 'Firefox', 07:33:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:33:06 INFO - 'branch': 'Firefox', 07:33:06 INFO - 'buildbot_json_path': 'buildprops.json', 07:33:06 INFO - 'config_files': ('talos/mac_config.py',), 07:33:06 INFO - 'default_actions': ('clobber', 07:33:06 INFO - 'read-buildbot-config', 07:33:06 INFO - 'download-and-extract', 07:33:06 INFO - 'populate-webroot', 07:33:06 INFO - 'create-virtualenv', 07:33:06 INFO - 'install', 07:33:06 INFO - 'run-tests'), 07:33:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:33:06 INFO - 'download_minidump_stackwalk': True, 07:33:06 INFO - 'download_symbols': 'ondemand', 07:33:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:33:06 INFO - 'tooltool.py': '/tools/tooltool.py', 07:33:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:33:06 INFO - '/tools/misc-python/virtualenv.py')}, 07:33:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:33:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:33:06 INFO - 'gecko_profile': False, 07:33:06 INFO - 'gecko_profile_interval': 0, 07:33:06 INFO - 'installer_path': 'installer.exe', 07:33:06 INFO - 'log_level': 'info', 07:33:06 INFO - 'log_name': 'talos', 07:33:06 INFO - 'log_to_console': True, 07:33:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:33:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:33:06 INFO - 'opt_config_files': (), 07:33:06 INFO - 'pip_index': False, 07:33:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:33:06 INFO - 'cmd': ('bash', 07:33:06 INFO - '-c', 07:33:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:33:06 INFO - 'enabled': True, 07:33:06 INFO - 'halt_on_failure': False, 07:33:06 INFO - 'name': 'check_screen_resolution'},), 07:33:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:33:06 INFO - 'cmd': ('bash', 07:33:06 INFO - '-c', 07:33:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:33:06 INFO - 'enabled': True, 07:33:06 INFO - 'halt_on_failure': False, 07:33:06 INFO - 'name': 'check_screen_resolution'},), 07:33:06 INFO - 'run_cmd_checks_enabled': True, 07:33:06 INFO - 'suite': 'dromaeojs-e10s', 07:33:06 INFO - 'system_bits': '32', 07:33:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:33:06 INFO - 'title': 't-yosemite-r7-0215', 07:33:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:33:06 INFO - 'use_talos_json': True, 07:33:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:33:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:33:06 INFO - 'work_dir': 'build'} 07:33:06 INFO - [mozharness: 2017-03-01 15:33:06.348947Z] Running clobber step. 07:33:06 INFO - Running pre-action listener: _resource_record_pre_action 07:33:06 INFO - Running main action method: clobber 07:33:06 INFO - rmtree: /builds/slave/test/build 07:33:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:33:08 INFO - Running post-action listener: _resource_record_post_action 07:33:08 INFO - [mozharness: 2017-03-01 15:33:08.294708Z] Finished clobber step (success) 07:33:08 INFO - [mozharness: 2017-03-01 15:33:08.294831Z] Running read-buildbot-config step. 07:33:08 INFO - Running pre-action listener: _resource_record_pre_action 07:33:08 INFO - Running main action method: read_buildbot_config 07:33:08 INFO - Using buildbot properties: 07:33:08 INFO - { 07:33:08 INFO - "project": "", 07:33:08 INFO - "product": "firefox", 07:33:08 INFO - "script_repo_revision": "production", 07:33:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:33:08 INFO - "repository": "", 07:33:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:33:08 INFO - "buildid": "20170301072336", 07:33:08 INFO - "stage_platform": "macosx64", 07:33:08 INFO - "basedir": "/builds/slave/test", 07:33:08 INFO - "buildnumber": 161, 07:33:08 INFO - "platform": "yosemite_r7", 07:33:08 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:33:08 INFO - "slavebuilddir": "test", 07:33:08 INFO - "branch": "mozilla-central", 07:33:08 INFO - "revision": "34c6c2f302e7b48e3ad2cec575cbd34d423a9d32", 07:33:08 INFO - "slavename": "t-yosemite-r7-0215", 07:33:08 INFO - "builduid": "7c30fda4e6d84bbdafbc45cd8658a9b4", 07:33:08 INFO - "repo_path": "mozilla-central" 07:33:08 INFO - } 07:33:08 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.dmg. 07:33:08 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.test_packages.json. 07:33:08 INFO - Running post-action listener: _resource_record_post_action 07:33:08 INFO - [mozharness: 2017-03-01 15:33:08.298201Z] Finished read-buildbot-config step (success) 07:33:08 INFO - [mozharness: 2017-03-01 15:33:08.298334Z] Running download-and-extract step. 07:33:08 INFO - Running pre-action listener: _resource_record_pre_action 07:33:08 INFO - Running main action method: download_and_extract 07:33:08 INFO - mkdir: /builds/slave/test/build/tests 07:33:08 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:08 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.test_packages.json 07:33:08 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 07:33:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 07:33:08 INFO - Downloaded 1199 bytes. 07:33:08 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 07:33:08 INFO - Using the following test package requirements: 07:33:08 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 07:33:08 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:33:08 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 07:33:08 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:33:08 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 07:33:08 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:33:08 INFO - u'jsshell-mac.zip'], 07:33:08 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:33:08 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 07:33:08 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 07:33:08 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:33:08 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 07:33:08 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:33:08 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 07:33:08 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:33:08 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 07:33:08 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:33:08 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 07:33:08 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:33:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.common.tests.zip 07:33:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:33:08 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.common.tests.zip into memory 07:33:09 INFO - Expected file size: 51762826 07:33:09 INFO - Obtained file size: 51762826 07:33:25 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:33:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.common.tests.zip 07:33:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:33:25 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.common.tests.zip into memory 07:33:26 INFO - Expected file size: 51762826 07:33:26 INFO - Obtained file size: 51762826 07:33:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.talos.tests.zip 07:33:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:33:41 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 07:33:42 INFO - Expected file size: 14404421 07:33:42 INFO - Obtained file size: 14404421 07:33: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:42 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.dmg 07:33:42 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 07:33:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 07:33:44 INFO - Downloaded 74973774 bytes. 07:33:44 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.dmg 07:33:44 INFO - mkdir: /builds/slave/test/properties 07:33:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:33:44 INFO - Writing to file /builds/slave/test/properties/build_url 07:33:44 INFO - Contents: 07:33:44 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.dmg 07:33:44 INFO - Running post-action listener: _resource_record_post_action 07:33:44 INFO - Running post-action listener: set_extra_try_arguments 07:33:44 INFO - [mozharness: 2017-03-01 15:33:44.417713Z] Finished download-and-extract step (success) 07:33:44 INFO - [mozharness: 2017-03-01 15:33:44.417863Z] Running populate-webroot step. 07:33:44 INFO - Running pre-action listener: _resource_record_pre_action 07:33:44 INFO - Running main action method: populate_webroot 07:33:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:33:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:33:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:33:44 INFO - '--tppagecycles', 07:33:44 INFO - '7'], 07:33:44 INFO - 'tests': ['tsvgm']}}, 07:33:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:33:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:33:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:33:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:33:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:33:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:33:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:33:44 INFO - 'talos_options': ['--disable-e10s'], 07:33:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:33:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:33:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:33:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:33:44 INFO - 'talos_options': ['--disable-e10s'], 07:33:44 INFO - 'tests': ['damp', 'tps']}, 07:33:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:33:44 INFO - 'tests': ['damp', 'tps']}, 07:33:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:33:44 INFO - 'tests': ['dromaeo_dom']}, 07:33:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:33:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:33:44 INFO - 'tests': ['basic_compositor_video']}, 07:33:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:33:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:33:44 INFO - 'tests': ['a11yr', 07:33:44 INFO - 'ts_paint', 07:33:44 INFO - 'tpaint', 07:33:44 INFO - 'sessionrestore', 07:33:44 INFO - 'sessionrestore_no_auto_restore', 07:33:44 INFO - 'tabpaint']}, 07:33:44 INFO - 'other-e10s': {'tests': ['a11yr', 07:33:44 INFO - 'ts_paint', 07:33:44 INFO - 'tpaint', 07:33:44 INFO - 'sessionrestore', 07:33:44 INFO - 'sessionrestore_no_auto_restore', 07:33:44 INFO - 'tabpaint']}, 07:33:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:33:44 INFO - 'ts_paint', 07:33:44 INFO - 'tpaint', 07:33:44 INFO - 'sessionrestore', 07:33:44 INFO - 'sessionrestore_no_auto_restore', 07:33:44 INFO - 'tabpaint']}, 07:33:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:33:44 INFO - 'ts_paint', 07:33:44 INFO - 'tpaint', 07:33:44 INFO - 'sessionrestore', 07:33:44 INFO - 'sessionrestore_no_auto_restore', 07:33:44 INFO - 'tabpaint']}, 07:33:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:33:44 INFO - 'tests': ['a11yr', 07:33:44 INFO - 'ts_paint', 07:33:44 INFO - 'tpaint', 07:33:44 INFO - 'sessionrestore', 07:33:44 INFO - 'sessionrestore_no_auto_restore', 07:33:44 INFO - 'tabpaint']}, 07:33:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:33:44 INFO - 'tests': ['a11yr', 07:33:44 INFO - 'ts_paint', 07:33:44 INFO - 'tpaint', 07:33:44 INFO - 'sessionrestore', 07:33:44 INFO - 'sessionrestore_no_auto_restore', 07:33:44 INFO - 'tabpaint']}, 07:33:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:33:44 INFO - 'tests': ['tsvgx', 07:33:44 INFO - 'tsvgr_opacity', 07:33:44 INFO - 'tart', 07:33:44 INFO - 'tscrollx', 07:33:44 INFO - 'cart', 07:33:44 INFO - 'tsvg_static']}, 07:33:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:33:44 INFO - 'tsvgr_opacity', 07:33:44 INFO - 'tart', 07:33:44 INFO - 'tscrollx', 07:33:44 INFO - 'cart', 07:33:44 INFO - 'tsvg_static']}, 07:33:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:33:44 INFO - 'talos_options': ['--disable-e10s'], 07:33:44 INFO - 'tests': ['tp5o']}, 07:33:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:33:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:33:44 INFO - 'talos_options': ['--disable-e10s', 07:33:44 INFO - '--xperf_path', 07:33:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:33:44 INFO - 'tests': ['tp5n']}, 07:33:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:33:44 INFO - 'talos_options': ['--xperf_path', 07:33:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:33:44 INFO - 'tests': ['tp5n']}}, 07:33:44 INFO - 'talos.zip': {'path': '', 07:33:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:33:44 INFO - Running post-action listener: _resource_record_post_action 07:33:44 INFO - [mozharness: 2017-03-01 15:33:44.436062Z] Finished populate-webroot step (success) 07:33:44 INFO - [mozharness: 2017-03-01 15:33:44.436156Z] Running create-virtualenv step. 07:33:44 INFO - Running pre-action listener: _resource_record_pre_action 07:33:44 INFO - Running main action method: create_virtualenv 07:33:44 INFO - Creating virtualenv /builds/slave/test/build/venv 07:33:44 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 07:33:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:33:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:33:44 INFO - Using real prefix '/tools/python27' 07:33:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:33:45 INFO - Installing distribute.............................................................................................................................................................................................done. 07:33:48 INFO - Installing pip.................done. 07:33:48 INFO - Return code: 0 07:33:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:33:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:33:48 INFO - Reading from file tmpfile_stdout 07:33:48 INFO - Output received: 07:33:48 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:33:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:33:48 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:48 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:48 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 0x10c9f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0d3dad450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7d7d30>, '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 0x10c6fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ccbe8>, '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.scRvxXCVV4/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.66ky427u0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 07:33:48 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 07:33:48 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 07:33:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.scRvxXCVV4/Render', 07:33:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:48 INFO - 'HOME': '/Users/cltbld', 07:33:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:48 INFO - 'LOGNAME': 'cltbld', 07:33:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:48 INFO - 'MOZ_NO_REMOTE': '1', 07:33:48 INFO - 'NO_EM_RESTART': '1', 07:33:48 INFO - 'PAGER': '/bin/cat', 07:33:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:48 INFO - 'PWD': '/builds/slave/test', 07:33:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:48 INFO - 'SHELL': '/bin/bash', 07:33:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66ky427u0D/Listeners', 07:33:48 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 07:33:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:48 INFO - 'USER': 'cltbld', 07:33:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:48 INFO - 'XPC_FLAGS': '0x0', 07:33:48 INFO - 'XPC_SERVICE_NAME': '0', 07:33:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:48 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 07:33:48 INFO - Cleaning up... 07:33:48 INFO - Return code: 0 07:33:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:33:48 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:48 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:48 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 0x10c9f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0d3dad450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7d7d30>, '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 0x10c6fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ccbe8>, '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.scRvxXCVV4/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.66ky427u0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 07:33:48 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 07:33:48 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 07:33:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.scRvxXCVV4/Render', 07:33:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:48 INFO - 'HOME': '/Users/cltbld', 07:33:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:48 INFO - 'LOGNAME': 'cltbld', 07:33:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:48 INFO - 'MOZ_NO_REMOTE': '1', 07:33:48 INFO - 'NO_EM_RESTART': '1', 07:33:48 INFO - 'PAGER': '/bin/cat', 07:33:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:48 INFO - 'PWD': '/builds/slave/test', 07:33:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:48 INFO - 'SHELL': '/bin/bash', 07:33:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66ky427u0D/Listeners', 07:33:48 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 07:33:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:48 INFO - 'USER': 'cltbld', 07:33:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:48 INFO - 'XPC_FLAGS': '0x0', 07:33:48 INFO - 'XPC_SERVICE_NAME': '0', 07:33:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:48 INFO - Downloading/unpacking psutil>=3.1.1 07:33:48 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 07:33:48 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 07:33:48 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 07:33:48 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 07:33:48 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 07:33:48 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 07:33:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:33:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:33:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:33:52 INFO - Installing collected packages: psutil 07:33:52 INFO - Running setup.py install for psutil 07:33:52 INFO - building 'psutil._psutil_osx' extension 07:33:52 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 07:33:52 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 07:33:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:33:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:33:53 INFO - building 'psutil._psutil_posix' extension 07:33:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:33:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:33:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:33:53 INFO - ^ 07:33:53 INFO - 1 warning generated. 07:33:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:33:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:33:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:33:53 INFO - Successfully installed psutil 07:33:53 INFO - Cleaning up... 07:33:53 INFO - Return code: 0 07:33:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:33:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0d3dad450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7d7d30>, '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 0x10c6fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ccbe8>, '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.scRvxXCVV4/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.66ky427u0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 07:33:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:33:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:33:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.scRvxXCVV4/Render', 07:33:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:53 INFO - 'HOME': '/Users/cltbld', 07:33:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:53 INFO - 'LOGNAME': 'cltbld', 07:33:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:53 INFO - 'MOZ_NO_REMOTE': '1', 07:33:53 INFO - 'NO_EM_RESTART': '1', 07:33:53 INFO - 'PAGER': '/bin/cat', 07:33:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:53 INFO - 'PWD': '/builds/slave/test', 07:33:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:53 INFO - 'SHELL': '/bin/bash', 07:33:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66ky427u0D/Listeners', 07:33:53 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 07:33:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:53 INFO - 'USER': 'cltbld', 07:33:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:53 INFO - 'XPC_FLAGS': '0x0', 07:33:53 INFO - 'XPC_SERVICE_NAME': '0', 07:33:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:33:53 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 07:33:53 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 07:33:53 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 07:33:53 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 07:33:53 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 07:33:53 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 07:33:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:33:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:33:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:33:56 INFO - Installing collected packages: mozsystemmonitor 07:33:56 INFO - Running setup.py install for mozsystemmonitor 07:33:56 INFO - Successfully installed mozsystemmonitor 07:33:56 INFO - Cleaning up... 07:33:56 INFO - Return code: 0 07:33:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:33: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:56 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0d3dad450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7d7d30>, '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 0x10c6fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ccbe8>, '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.scRvxXCVV4/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.66ky427u0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 07:33:56 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==2.5.1'] in /builds/slave/test/build 07:33:56 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==2.5.1 07:33:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.scRvxXCVV4/Render', 07:33:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:56 INFO - 'HOME': '/Users/cltbld', 07:33:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:56 INFO - 'LOGNAME': 'cltbld', 07:33:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:56 INFO - 'MOZ_NO_REMOTE': '1', 07:33:56 INFO - 'NO_EM_RESTART': '1', 07:33:56 INFO - 'PAGER': '/bin/cat', 07:33:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:56 INFO - 'PWD': '/builds/slave/test', 07:33:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:56 INFO - 'SHELL': '/bin/bash', 07:33:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66ky427u0D/Listeners', 07:33:56 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 07:33:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:56 INFO - 'USER': 'cltbld', 07:33:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:56 INFO - 'XPC_FLAGS': '0x0', 07:33:56 INFO - 'XPC_SERVICE_NAME': '0', 07:33:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:56 INFO - Downloading/unpacking jsonschema==2.5.1 07:33:56 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 07:33:56 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 07:33:56 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 07:33:56 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 07:33:56 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 07:33:56 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 07:33:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:33:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:33:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:59 INFO - Downloading functools32-3.2.3-2.tar.gz 07:33:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:34:00 INFO - warning: no files found matching '*.txt' 07:34:00 INFO - no previously-included directories found matching 'build' 07:34:00 INFO - no previously-included directories found matching 'dist' 07:34:00 INFO - no previously-included directories found matching '.git*' 07:34:00 INFO - Installing collected packages: jsonschema, functools32 07:34:00 INFO - Running setup.py install for functools32 07:34:00 INFO - warning: no files found matching '*.txt' 07:34:00 INFO - no previously-included directories found matching 'build' 07:34:00 INFO - no previously-included directories found matching 'dist' 07:34:00 INFO - no previously-included directories found matching '.git*' 07:34:00 INFO - Successfully installed jsonschema functools32 07:34:00 INFO - Cleaning up... 07:34:00 INFO - Return code: 0 07:34:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0d3dad450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7d7d30>, '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 0x10c6fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ccbe8>, '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.scRvxXCVV4/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.66ky427u0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 07:34:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:34:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:34:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.scRvxXCVV4/Render', 07:34:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:00 INFO - 'HOME': '/Users/cltbld', 07:34:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:00 INFO - 'LOGNAME': 'cltbld', 07:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:00 INFO - 'MOZ_NO_REMOTE': '1', 07:34:00 INFO - 'NO_EM_RESTART': '1', 07:34:00 INFO - 'PAGER': '/bin/cat', 07:34:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:00 INFO - 'PWD': '/builds/slave/test', 07:34:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:00 INFO - 'SHELL': '/bin/bash', 07:34:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66ky427u0D/Listeners', 07:34:00 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 07:34:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:00 INFO - 'USER': 'cltbld', 07:34:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:00 INFO - 'XPC_FLAGS': '0x0', 07:34:00 INFO - 'XPC_SERVICE_NAME': '0', 07:34:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:34:01 INFO - Cleaning up... 07:34:01 INFO - Return code: 0 07:34:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0d3dad450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7d7d30>, '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 0x10c6fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ccbe8>, '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.scRvxXCVV4/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.66ky427u0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 07:34:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:34:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:34:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.scRvxXCVV4/Render', 07:34:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:01 INFO - 'HOME': '/Users/cltbld', 07:34:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:01 INFO - 'LOGNAME': 'cltbld', 07:34:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:01 INFO - 'MOZ_NO_REMOTE': '1', 07:34:01 INFO - 'NO_EM_RESTART': '1', 07:34:01 INFO - 'PAGER': '/bin/cat', 07:34:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:01 INFO - 'PWD': '/builds/slave/test', 07:34:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:01 INFO - 'SHELL': '/bin/bash', 07:34:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66ky427u0D/Listeners', 07:34:01 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 07:34:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:01 INFO - 'USER': 'cltbld', 07:34:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:01 INFO - 'XPC_FLAGS': '0x0', 07:34:01 INFO - 'XPC_SERVICE_NAME': '0', 07:34:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:01 INFO - Downloading/unpacking blobuploader==1.2.4 07:34: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 07:34: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 07:34: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 07:34: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 07:34: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 07:34: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 07:34:04 INFO - Downloading blobuploader-1.2.4.tar.gz 07:34:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:34:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:34:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:06 INFO - Downloading docopt-0.6.1.tar.gz 07:34:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:34:06 INFO - Installing collected packages: blobuploader, requests, docopt 07:34:06 INFO - Running setup.py install for blobuploader 07:34:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:34:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:34:06 INFO - Running setup.py install for requests 07:34:06 INFO - Running setup.py install for docopt 07:34:07 INFO - Successfully installed blobuploader requests docopt 07:34:07 INFO - Cleaning up... 07:34:07 INFO - Return code: 0 07:34:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:34: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0d3dad450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7d7d30>, '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 0x10c6fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ccbe8>, '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.scRvxXCVV4/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.66ky427u0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 07:34:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:34:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:34:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.scRvxXCVV4/Render', 07:34:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:07 INFO - 'HOME': '/Users/cltbld', 07:34:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:07 INFO - 'LOGNAME': 'cltbld', 07:34:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:07 INFO - 'MOZ_NO_REMOTE': '1', 07:34:07 INFO - 'NO_EM_RESTART': '1', 07:34:07 INFO - 'PAGER': '/bin/cat', 07:34:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:07 INFO - 'PWD': '/builds/slave/test', 07:34:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:07 INFO - 'SHELL': '/bin/bash', 07:34:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66ky427u0D/Listeners', 07:34:07 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 07:34:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:07 INFO - 'USER': 'cltbld', 07:34:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:07 INFO - 'XPC_FLAGS': '0x0', 07:34:07 INFO - 'XPC_SERVICE_NAME': '0', 07:34:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:34:07 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-5VpOCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:34:07 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-mfsIys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:34:07 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-s4AbIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:34:07 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-47gLFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:34:07 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-WSBIV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:34:07 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-iorXWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:34:07 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-J4Y_Kn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:34:08 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-dgRpWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:34:08 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-ZWLoKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:34:08 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-qKrMTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:34:08 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-Ume0uI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:34:08 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-2HaAAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:34:08 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-K7AWLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:34:08 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-fnTqQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:34:08 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-4rRvHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:34:08 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-m8Iylg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:34:09 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-qZSIcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:34:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:34:09 INFO - Running setup.py install for manifestparser 07:34:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:34:09 INFO - Running setup.py install for mozcrash 07:34:09 INFO - Running setup.py install for mozdebug 07:34:09 INFO - Running setup.py install for mozdevice 07:34:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:34:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:34:09 INFO - Running setup.py install for mozfile 07:34:09 INFO - Running setup.py install for mozhttpd 07:34:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:34:10 INFO - Running setup.py install for mozinfo 07:34:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:34:10 INFO - Running setup.py install for mozInstall 07:34:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:34:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:34:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:34:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:34:10 INFO - Running setup.py install for mozleak 07:34:10 INFO - Running setup.py install for mozlog 07:34:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:34:10 INFO - Running setup.py install for moznetwork 07:34:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:34:10 INFO - Running setup.py install for mozprocess 07:34:10 INFO - Running setup.py install for mozprofile 07:34:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:34:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:34:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:34:11 INFO - Running setup.py install for mozrunner 07:34:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:34:11 INFO - Running setup.py install for mozscreenshot 07:34:11 INFO - Running setup.py install for moztest 07:34:11 INFO - Running setup.py install for mozversion 07:34:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:34:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:34:11 INFO - Cleaning up... 07:34:11 INFO - Return code: 0 07:34:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0d3dad450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7d7d30>, '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 0x10c6fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ccbe8>, '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.scRvxXCVV4/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.66ky427u0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 07:34:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:34:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:34:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.scRvxXCVV4/Render', 07:34:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:11 INFO - 'HOME': '/Users/cltbld', 07:34:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:11 INFO - 'LOGNAME': 'cltbld', 07:34:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:11 INFO - 'MOZ_NO_REMOTE': '1', 07:34:11 INFO - 'NO_EM_RESTART': '1', 07:34:11 INFO - 'PAGER': '/bin/cat', 07:34:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:11 INFO - 'PWD': '/builds/slave/test', 07:34:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:11 INFO - 'SHELL': '/bin/bash', 07:34:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66ky427u0D/Listeners', 07:34:11 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 07:34:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:11 INFO - 'USER': 'cltbld', 07:34:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:11 INFO - 'XPC_FLAGS': '0x0', 07:34:11 INFO - 'XPC_SERVICE_NAME': '0', 07:34:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:34:12 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-W6thTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:34:12 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-4Doi8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 07:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:34:12 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-980qKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:34:12 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-JYQeTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 07:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:34:12 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-WwiIIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:34:12 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)) 07:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:34:12 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-DrnrI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:34:12 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)) 07:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:34:12 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-WuMPEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:34:12 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-qPVLDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:34:12 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-emBpCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:34:12 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-I7l8nu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 07:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:34:13 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-Jm0xJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:34:13 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-4Hm1qW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 07:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:34:13 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-AQhr2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:34:13 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)) 07:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:34:13 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-xE5XHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 07:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:34:13 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-nGjmih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:34:13 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)) 07:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:34:13 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-GMwWxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 07:34:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:34:13 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-9tvgwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:34:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:34: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 07:34: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 07:34: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 07:34: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 07:34: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 07:34: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 07:34:16 INFO - Downloading blessings-1.6.tar.gz 07:34:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:34:16 INFO - Installing collected packages: blessings 07:34:16 INFO - Running setup.py install for blessings 07:34:16 INFO - Successfully installed blessings 07:34:16 INFO - Cleaning up... 07:34:16 INFO - Return code: 0 07:34:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:34:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:34:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:34:17 INFO - Reading from file tmpfile_stdout 07:34:17 INFO - Current package versions: 07:34:17 INFO - blessings == 1.6 07:34:17 INFO - blobuploader == 1.2.4 07:34:17 INFO - docopt == 0.6.1 07:34:17 INFO - functools32 == 3.2.3-2 07:34:17 INFO - jsonschema == 2.5.1 07:34:17 INFO - manifestparser == 1.1 07:34:17 INFO - mozInstall == 1.12 07:34:17 INFO - mozcrash == 1.0 07:34:17 INFO - mozdebug == 0.1 07:34:17 INFO - mozdevice == 0.49 07:34:17 INFO - mozfile == 1.2 07:34:17 INFO - mozhttpd == 0.7 07:34:17 INFO - mozinfo == 0.9 07:34:17 INFO - mozleak == 0.1 07:34:17 INFO - mozlog == 3.4 07:34:17 INFO - moznetwork == 0.27 07:34:17 INFO - mozprocess == 0.25 07:34:17 INFO - mozprofile == 0.28 07:34:17 INFO - mozrunner == 6.13 07:34:17 INFO - mozscreenshot == 0.1 07:34:17 INFO - mozsystemmonitor == 0.3 07:34:17 INFO - moztest == 0.8 07:34:17 INFO - mozversion == 1.4 07:34:17 INFO - psutil == 3.1.1 07:34:17 INFO - requests == 1.2.3 07:34:17 INFO - wsgiref == 0.1.2 07:34:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0d3dad450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7d7d30>, '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 0x10c6fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ccbe8>, '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.scRvxXCVV4/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.66ky427u0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 07:34:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:34:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:34:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.scRvxXCVV4/Render', 07:34:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:17 INFO - 'HOME': '/Users/cltbld', 07:34:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:17 INFO - 'LOGNAME': 'cltbld', 07:34:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:17 INFO - 'MOZ_NO_REMOTE': '1', 07:34:17 INFO - 'NO_EM_RESTART': '1', 07:34:17 INFO - 'PAGER': '/bin/cat', 07:34:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:17 INFO - 'PWD': '/builds/slave/test', 07:34:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:17 INFO - 'SHELL': '/bin/bash', 07:34:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66ky427u0D/Listeners', 07:34:17 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 07:34:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:17 INFO - 'USER': 'cltbld', 07:34:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:17 INFO - 'XPC_FLAGS': '0x0', 07:34:17 INFO - 'XPC_SERVICE_NAME': '0', 07:34:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:17 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)) 07:34:17 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)) 07:34:17 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)) 07:34:17 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)) 07:34:17 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)) 07:34:17 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)) 07:34:17 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)) 07:34:17 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)) 07:34:17 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)) 07:34:17 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)) 07:34:17 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)) 07:34:17 INFO - Cleaning up... 07:34:17 INFO - Return code: 0 07:34:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:17 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 0x10c9f9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9fc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0d3dad450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7d7d30>, '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 0x10c6fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ccbe8>, '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.scRvxXCVV4/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.66ky427u0D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 07:34:17 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 07:34:17 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 07:34:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.scRvxXCVV4/Render', 07:34:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:17 INFO - 'HOME': '/Users/cltbld', 07:34:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:17 INFO - 'LOGNAME': 'cltbld', 07:34:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:17 INFO - 'MOZ_NO_REMOTE': '1', 07:34:17 INFO - 'NO_EM_RESTART': '1', 07:34:17 INFO - 'PAGER': '/bin/cat', 07:34:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:17 INFO - 'PWD': '/builds/slave/test', 07:34:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:17 INFO - 'SHELL': '/bin/bash', 07:34:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66ky427u0D/Listeners', 07:34:17 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 07:34:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:17 INFO - 'USER': 'cltbld', 07:34:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:17 INFO - 'XPC_FLAGS': '0x0', 07:34:17 INFO - 'XPC_SERVICE_NAME': '0', 07:34:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:34:17 INFO - Cleaning up... 07:34:17 INFO - Return code: 0 07:34:17 INFO - Running post-action listener: _resource_record_post_action 07:34:17 INFO - Running post-action listener: _start_resource_monitoring 07:34:17 INFO - Starting resource monitoring. 07:34:17 INFO - [mozharness: 2017-03-01 15:34:17.712018Z] Finished create-virtualenv step (success) 07:34:17 INFO - [mozharness: 2017-03-01 15:34:17.713059Z] Running install step. 07:34:17 INFO - Running pre-action listener: _resource_record_pre_action 07:34:17 INFO - Running main action method: install 07:34:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:34:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:34:17 INFO - Reading from file tmpfile_stdout 07:34:17 INFO - Detecting whether we're running mozinstall >=1.0... 07:34:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:34:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:34:18 INFO - Reading from file tmpfile_stdout 07:34:18 INFO - Output received: 07:34:18 INFO - Usage: mozinstall [options] installer 07:34:18 INFO - Options: 07:34:18 INFO - -h, --help show this help message and exit 07:34:18 INFO - -d DEST, --destination=DEST 07:34:18 INFO - Directory to install application into. [default: 07:34:18 INFO - "/builds/slave/test"] 07:34:18 INFO - --app=APP Application being installed. [default: firefox] 07:34:18 INFO - mkdir: /builds/slave/test/build/application 07:34:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:34:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:34:38 INFO - Reading from file tmpfile_stdout 07:34:38 INFO - Output received: 07:34:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:34:38 INFO - Running post-action listener: _resource_record_post_action 07:34:38 INFO - [mozharness: 2017-03-01 15:34:38.382488Z] Finished install step (success) 07:34:38 INFO - [mozharness: 2017-03-01 15:34:38.382668Z] Running run-tests step. 07:34:38 INFO - Running pre-action listener: _resource_record_pre_action 07:34:38 INFO - Running main action method: run_tests 07:34:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:34:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:34:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:34:38 INFO - 2017-03-01 07:34:38.397 screenresolution[1902:16901] starting screenresolution argv=screenresolution get 07:34:38 INFO - 2017-03-01 07:34:38.400 screenresolution[1902:16901] Display 0: 1600x1200x32@60 07:34:38 INFO - 2017-03-01 07:34:38.406 screenresolution[1903:16905] starting screenresolution argv=screenresolution list 07:34:38 INFO - Available Modes on Display 0 07:34:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:34:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:34:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:34:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:34:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:34:38 INFO - Intel Iris: 07:34:38 INFO - Chipset Model: Intel Iris 07:34:38 INFO - Type: GPU 07:34:38 INFO - Bus: Built-In 07:34:38 INFO - VRAM (Dynamic, Max): 1536 MB 07:34:38 INFO - Vendor: Intel (0x8086) 07:34:38 INFO - Device ID: 0x0a2e 07:34:38 INFO - Revision ID: 0x0009 07:34:38 INFO - Displays: 07:34:38 INFO - Display: 07:34:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:34:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:34:38 INFO - Display Serial Number: 1600x1200 60 07:34:38 INFO - Main Display: Yes 07:34:38 INFO - Mirror: Off 07:34:38 INFO - Online: Yes 07:34:38 INFO - Rotation: Supported 07:34:38 INFO - Return code: 0 07:34:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:34:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:34:38 INFO - Python 2.7.3 07:34:38 INFO - Return code: 0 07:34:38 INFO - grabbing minidump binary from tooltool 07:34:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:38 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 0x10c7d7d30>, '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 0x10c6fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ccbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:34:38 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 07:34:38 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 07:34:38 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 07:34:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:34:38 INFO - Return code: 0 07:34:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:34:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:34:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:34:38 INFO - ENV: RUST_BACKTRACE is now 1 07:34:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:34:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:34:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0215', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:34:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0215 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:34:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.scRvxXCVV4/Render', 07:34:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:38 INFO - 'HOME': '/Users/cltbld', 07:34:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:38 INFO - 'LOGNAME': 'cltbld', 07:34:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:34:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:34:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:38 INFO - 'MOZ_NO_REMOTE': '1', 07:34:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:34:38 INFO - 'NO_EM_RESTART': '1', 07:34:38 INFO - 'PAGER': '/bin/cat', 07:34:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:38 INFO - 'PWD': '/builds/slave/test', 07:34:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:34:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:38 INFO - 'RUST_BACKTRACE': '1', 07:34:38 INFO - 'SHELL': '/bin/bash', 07:34:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.66ky427u0D/Listeners', 07:34:38 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 07:34:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:38 INFO - 'USER': 'cltbld', 07:34:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:38 INFO - 'XPC_FLAGS': '0x0', 07:34:38 INFO - 'XPC_SERVICE_NAME': '0', 07:34:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0215', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:34:38 INFO - mozversion application_buildid: 20170301024203 07:34:38 INFO - mozversion application_changeset: 34c6c2f302e7b48e3ad2cec575cbd34d423a9d32 07:34:38 INFO - mozversion application_display_name: Nightly 07:34:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:34:39 INFO - mozversion application_name: Firefox 07:34:39 INFO - mozversion application_remotingname: firefox 07:34:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:34:39 INFO - mozversion application_vendor: Mozilla 07:34:39 INFO - mozversion application_version: 54.0a1 07:34:39 INFO - mozversion platform_buildid: 20170301024203 07:34:39 INFO - mozversion platform_changeset: 34c6c2f302e7b48e3ad2cec575cbd34d423a9d32 07:34:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:34:39 INFO - mozversion platform_version: 54.0a1 07:34:39 INFO - using testdate: 1488382478 07:34:39 INFO - actual date: 1488382478 07:34:39 INFO - starting webserver on 'localhost:49367' 07:34:39 INFO - SUITE-START | Running 2 tests 07:34:39 INFO - TEST-START | dromaeo_css 07:34:39 INFO - operating with platform_type : mac_ 07:34:39 INFO - Initialising browser for dromaeo_css test... 07:34:39 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpziXLuu/profile http://localhost:49367/getInfo.html) 07:34:39 INFO - PROCESS | 1913 | 1488382479541 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 07:34:39 INFO - PROCESS | 1913 | 1488382479542 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 07:34:39 INFO - PROCESS | 1913 | 1488382479583 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 07:34:42 INFO - PROCESS | 1913 | 2017-03-01 07:34:42.174 plugin-container[1915:17067] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494f, name = 'com.apple.tsm.portname' 07:34:42 INFO - PROCESS | 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:34:42 INFO - PROCESS | 1913 | 2017-03-01 07:34:42.178 plugin-container[1915:17067] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9323, name = 'com.apple.CFPasteboardClient' 07:34:42 INFO - PROCESS | 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:34:42 INFO - PROCESS | 1913 | 2017-03-01 07:34:42.178 plugin-container[1915:17067] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:34:42 INFO - PROCESS | 1913 | __metrics Screen width/height:1600/1200 07:34:42 INFO - PROCESS | 1913 | colorDepth:24 07:34:42 INFO - PROCESS | 1913 | Browser inner width/height: 1024/663 07:34:42 INFO - PROCESS | 1913 | __metrics 07:34:42 ERROR - PROCESS | 1913 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 07:34:42 INFO - PROCESS | 1913 | 1488382482388 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 07:34:43 INFO - TEST-INFO | 1913: exit 0 07:34:43 INFO - Browser initialized. 07:34:43 INFO - Running cycle 1/1 for dromaeo_css test... 07:34:43 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpziXLuu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:34:43 INFO - PROCESS | 1916 | 1488382483452 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 07:34:43 INFO - PROCESS | 1916 | 1488382483453 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 07:34:45 INFO - PROCESS | 1916 | 2017-03-01 07:34:45.671 plugin-container[1917:17203] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x872b, name = 'com.apple.tsm.portname' 07:34:45 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:34:45 INFO - PROCESS | 1916 | 2017-03-01 07:34:45.672 plugin-container[1917:17203] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 07:34:45 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:34:45 INFO - PROCESS | 1916 | 2017-03-01 07:34:45.672 plugin-container[1917:17203] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:34:55 INFO - PROCESS | 1916 | RSS: Main: 235515904 07:34:55 INFO - PROCESS | 1916 | 07:37:58 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49367/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49367/tests/dromaeo/cssquery-ext.html) 07:38:00 INFO - PROCESS | 1916 | RSS: Main: 256942080 07:38:00 INFO - PROCESS | 1916 | 07:39:43 INFO - PROCESS | 1916 | ************************* 07:39:43 INFO - PROCESS | 1916 | A coding exception was thrown and uncaught in a Task. 07:39:43 INFO - PROCESS | 1916 | 07:39:43 ERROR - PROCESS | 1916 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:39:43 INFO - PROCESS | 1916 | Full stack: 07:39:43 INFO - PROCESS | 1916 | ************************* 07:41:02 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49367/tests/dromaeo/cssquery-ext.html (next: http://localhost:49367/tests/dromaeo/cssquery-jquery.html) 07:41:02 INFO - PROCESS | 1916 | RSS: Main: 262750208 07:41:02 INFO - PROCESS | 1916 | 07:44:05 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49367/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49367/tests/dromaeo/cssquery-mootools.html) 07:44:05 INFO - PROCESS | 1916 | RSS: Main: 274038784 07:44:05 INFO - PROCESS | 1916 | 07:47:07 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49367/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49367/tests/dromaeo/cssquery-prototype.html) 07:47:08 INFO - PROCESS | 1916 | RSS: Main: 267214848 07:47:08 INFO - PROCESS | 1916 | 07:50:11 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49367/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49367/tests/dromaeo/cssquery-yui.html) 07:50:12 INFO - PROCESS | 1916 | RSS: Main: 276918272 07:50:12 INFO - PROCESS | 1916 | 07:53:15 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49367/tests/dromaeo/cssquery-yui.html (next: http://localhost:49367/tests/dromaeo/cssquery-dojo.html) 07:53:15 INFO - PROCESS | 1916 | RSS: Main: 270860288 07:53:15 INFO - PROCESS | 1916 | 07:53:15 INFO - PROCESS | 1916 | __start_tp_report 07:53:15 INFO - PROCESS | 1916 | _x_x_mozilla_page_load 07:53:15 INFO - PROCESS | 1916 | _x_x_mozilla_page_load_details 07:53:15 INFO - PROCESS | 1916 | |i|pagename|runs| 07:53:15 INFO - PROCESS | 1916 | |0;dojo.html;2820.012995451591;2839.602455656208;3385.7291416686667;3459.0833429141267;3461.757676962612;5114.207297868837;5785.3346865110525;5834.124881267807;5878.706064696758;5897.616654917433;5762.078067509199;5823.650580965134;5823.951688696044;5857.6485410875375;5933.199018132551;5174.560162386202;5240.790368385269;5311.442298558653;5330.893382132355;5365.356157261134;5394.352677678684;5404;5404.865078294071;5409.215663728757;5418.078926582471;5151.536361727443;5185.792568297264;5192.221166824968;5261.789528418859;5300.549453296474;5213.661112027744;5263.605229607795;5270.156774916033;5308.309919710451;5347.037533244014;5197.688138711689;5227.085260079471;5229.346331708536;5240.187770895517;5271.973640131813;4226.788660566959;4488.259437192859;5580.637258578179;5705.372409035002;5783.913241301384;6121.026213050743;6189.566730328879;6224.813255602339;6239.563230573862;6278.560500764948;6326.367363263683;6355.014972679242;6372.5857819242065;6386.361363863576;6393.913034784051;5964.105384192319;5972.134040564094;6025.216721826135;6039.999999999956;6058.575899687068;5216.347956505437;5764.913526297109;6286.151369565142;6339.492840572743;6459.935400646027;5691.68695721731;6139.032198454786;6147.108669242934;6174.8147555573405;6199.1011303361665;6152.107944348134;6255.218097737783;6260.780872669435;6339.397757213057;6364.331745166784;6092.695365231721;6251.281102673251;6256.43692067707;6271.43557079865;6287.811365659038;6137.815865524041;6152.446279834837;6236.968815155895;6252.249730032334;6269.96865015672;4971.403431588233;4988.102141614471;4989.750512474435;5002.299678045077;5008.323876276656;4008.8797336079965;4024.1951609677594;4413.536578659258;4439.356293337448;4578.244589642738;5339.492748188994;6011.3086994995265;6059.212302400748;6067.998780201306;6070.0591408331775;5803.8549036274435;6193.628382297076;6303.558750887394;6335.11308416822;6336.429721325103;4792.424909010941;5792.333881603669;5923.289205295392;5956.791512297049;6101.176341193882;4917.459079501252;5567.888642227133;5576.860578485489;5585.050541407971;5590.2453168821685;6425.935740642627;6435.678216089083;6473.48212143018;6493.772717954849;6507.674616269168;6149.2620885494025;6179.81460556184;6216.347283535164;6226.968865155645;6248.562600617913;6529.967350163219;6536.182977127859;6548.1814773153355;6559.836004099936;6568.146141001639;6272.498200143887;6283.622982621057;6312.147860038836;6330.39861213184;6450.903236451551;6082.118092876596;6150.077488376601;6180.412860778219;6207;6321.462675672619;6113.5414843886;6228.750849965951;6247.687615619292;6255.280642726145;6290.150829637941;5869.882602348014;6032.065029920369;6219.844503887439;6252.781152659635;6259.061140828912;5843.766249349978;5893.322267939242;5964.7912323068485;6106.236720409949;6108.786192483241;5983.892979798751;6263.874722505433;6271.905921411091;6279.905801413074;6296;5885.411458854252;6030.216071910623;6043.697815109139;6083.6349819011;6160.876782464416 07:53:15 INFO - PROCESS | 1916 | |1;ext.html;715.2525610736783;782.4992005116725;804.3283857978588;936.1855185988192;938.5025936253779;32588;37240.62759372406;37690.81154594227;38627.420588691195;39186.412203816966;38065.23869522608;38265.808670956605;38306.04234894129;38327.23345533095;38409.807950960276;7722.343600793925;7843.333316668061;10274.589016439333;10729.141668666489;10740.946295268513;10562.735931601734;10666.573337066508;10670.093042091432;10680.359178449278;10684.78630427391;10633.78732425351;10661.040506354426;10691.679249622484;10695.46522673867;10794.406307653077;10605.045545900868;10630.04329610335;10659.733506662318;10673.252872298865;10685.091767199758;10318.432486213254;10350.378977261389;10390.636327728569;10393.792124157475;10404.115650169744;32560.511592326133;32720.672793272002;32745;32834.835825820956;32947.68209271626;3665.2303016366595;3676.209614932802;3680.595134535199;3681.5582130144285;3713.4801127842125;24822.255332340057;24958.37604059895;24980.250592482254;25083.372915677253;25528;2785.9047200615064;2854.785891058158;2865.498537755905;2873.841938693371;2960.689127641588;268023.999999998;332478;333144;336217;337922;36739.81630091886;42370.364444533356;43016.569834301255;43023;43079.35380969288;35804.28391432157;45051.324230136575;45119.09761804811;45170.32244516335;45174.28954262689;12737.93631031839;12745.107842451125;12819.16675416095;12830.037747168999;12841.357932103358;10794.946025269981;10861.45692715377;10879.12966962641;10886.564537418415;10893.564257429773;8338.583070846433;11559.826602600968;11626.76746465066;11662.950334469939;11763.470643821049;5444.5916556258435;5463.672179669233;5473.0969390050195;5513.751881165277;5515.696636685021;805.6092794994423;969.1955676788249;984.7538115471132;991.3358050106445;1012.0587853296357;21174;23231.53536929252;25379.61930571043;28364.85817570899;28707.56938645922;1061.4215252687304;1069.6042464288264;1075.1828610256105;1104.629948967105;1105.784372047443;12993.545225917047;14169.291535423188;14529;14646.714300693404;14808.407663693331;403589;405178;405531;407026;408219;42356.3646545302;42497.57502424936;42619.36070958938;42681.35977960333;44511.55488445139;1239.6528971887885;1387.854275301099;1415.6712208417096;1426.8287805463203;1431.169921445559;24874.12940547072;29450.915972219063;29881.70118298789;29896.115807943606;30049;42353.57646423496;44921;45159.54840451553;45301.773491132335;45440;410466;410522;411324;411470;412032;20751.584968300853;21407.67888481705;23222.419439514146;23457;23798.405039874142;42476.78761606172;43084;43656.78171609122;43907.560924390345;44028.67913962586;30411.54382684217;32925.34149317048;35234.8238258807;35243.11892202613;36055;31938;32243.35513289768;32608.83695581602;33089.834550827094;33161.83419082889;32924.835375822964;32986.505202421504;40057.599424006556;41183.382249265684;41438.37842432426;35941;38033;38212;38772.80613596914;39970;26065.869670651526;31448.842755786074;31505.527417088302;31954.36091278208;32345.706171752867 07:53:15 INFO - PROCESS | 1916 | |2;jquery.html;387.901085223268;388.18674876134526;398.5197836606889;417.38852580968876;427.08816676395907;13132.409041593128;14609;19858;21104.261350852725;21122.471938201554;20531.89734051328;21145.731256124644;21183.152673893026;21289.893550532266;21491.809097226047;6397.104405383238;6782.287859774702;7197.640117994079;7267.200607933123;7343.88984165238;6246.843828904291;7088.8303429934;7162.892556611629;7204.279572042807;7232.746853860116;7220.67506962188;7225.205227424979;7234.2765723427765;7241.782746517587;7247.3477387035155;6160.045192995093;6628.834279143011;7198.388137008386;7283.053203083618;7284.125904891394;6778.559393639447;6996.580205187705;7046.577205367695;7061;7064.328888755612;24650.630240546405;25167;25229.11698090558;25299.873500632562;25368.87315563429;2567.562204849401;2899.8260104393803;3077.7845550811453;3336.4828451590042;3380.509826075205;14337.928310358382;15105.546833594899;15473.226338683133;15842.683146337124;16049.438269660506;1900.3348827910258;1975.7530308711412;2004.3586052463218;2030.522827135622;2046.7646220684662;133520;143944.28027859793;150383;155485.22257388753;155811.99999999886;23479.530409391715;24486.632700509508;24666.260012199662;24716.5056698865;24778.876105619358;29743.85128074389;40126;40384.39423408651;40400.79799600983;40761.59238407578;8664;10462.68611941643;10996.340219586851;11176.217664763388;11184.38485883268;8725.563721813884;8752.299816014704;8796.78008049791;8925.241354484811;8938.50838203892;11220.55117795279;11521.779277368165;11548.884511154949;11722.120840936965;11738;3744.2776322891773;4023.8591649292134;4265.274903266452;4305.225059489259;4360.890286248688;810.9675612975415;903.9819203615891;1021.9437930913871;1036.8081904847477;1067.546292825546;15535.533933981773;17331.180226076332;19630.803691962898;20407.591848162952;20565.69151462729;759.502525845572;884.6284560484612;920.2362039507193;934.2385955446425;935.5322338830584;10097.697069087939;10373;10428.478576071318;10469.267151299337;10546.947265263778;724996.001599354;725571;733497;739954;757479;124895;158971.20514397355;161836.19081904515;163143.84280785912;165026.1748691249;977.9413235205768;997.5112195024386;1018.6485662446444;1018.7707765752647;1021.2034613001871;16888.324467021182;17005.404810831562;17236.396726114526;17443.30226790914;17722.822771772633;55471.7301856323;59839.50401240004;60360;61285.693571533644;61322;67685;87758;89485;90564;90634.54682726544;10683.145348372267;10967.335249241713;11149.888501115209;11359.261647992855;11392;12218.816717749063;12421.254724716546;12545.184563003375;12648.494060237488;12706.618801435972;8630.058236896648;8970.865437018581;10442.321249118782;10480.737981550523;10999.040345475463;9618.749562556823;9895.604175832887;10651.414172220602;11040.447977600927;11131.55473781072;10804.621838235624;10898.291611045257;11032.889671103187;11300.265482743594;11375.1468639854;9032.36773425854;10988.285761425483;11485.885141148481;11763.117766167743;11811.291322520676;10800.472911885401;11005.284656497302;11063.391513466837;11075.446227688422;11181.664550063511 07:53:15 INFO - PROCESS | 1916 | |3;mootools.html;1347.299404309759;1488.0625206120142;1500.6248437890533;1543.9922800385996;1547.2418514927685;9122.407043542165;9411.623535058607;10280.640177593787;10921.99999999999;10963.451827408628;10183.236257280714;10576.576936922533;10590.841137382946;10676.359418434899;10761.569537218522;2279.1567120165564;2330.673705681206;2333.323336232499;2336.8013718833918;2341.7892389684926;2299.5630830142304;2329.26628112145;2342.8711420871846;2344.2498400511845;2350.106959355451;2299.620562607168;2316.7104111986;2340.8829558522093;2342.355852140658;2344.800691941187;2277.20297895736;2282.6971159364716;2305.3775480620225;2306.1467257114973;2308.4344335637707;1926.7572415791876;2267.8979445924974;2309.21486694523;2311.7572654871333;2315.560043591729;10563.366198028143;10578.206634502443;10652.62715804943;10667.253292269545;10672.146228301717;5560.6941618210985;5720.599558030939;5731.971340143315;5742.942570574324;5751.734618383949;3129.874805007797;3150.936981260385;3302.9669703302898;3345.080102971678;3353.815540145291;1057.370864335719;1079.3146352066417;1094.3543309447464;1110.3615421132881;1128.3793913347627;14914.776278355834;14960.952733308564;15071.020383674917;15072.924635376863;15098.018628789203;4674.392328997209;4729.624130655579;4763.928116173903;4773.832915847928;4862.9756851216225;12252;12324.07569432296;12330.63008109758;12362.196457230251;12382.690432739073;5582.358028826656;5599.412061733541;5608.915866262009;5661.745221464962;5684.914726279109;3534.434490481562;4088.2845502037026;4943.8516844494725;5034.798608055637;5078.4413714491375;3918.2163567286657;3935.2719746846924;3947.289487892207;4059.127287633173;4096.508418989741;1116.1015382617072;1131.8754936956927;1143.3425780176265;1144.811106167473;1148.586508856811;762.4891322813728;802.2058162419163;832.2177153475714;849.0617867256592;867.2238346679686;6112.633242005494;6340.239171299381;6448.645324507103;6702.36327548882;6774.796756097325;912.4753266871576;924.4822899176482;991.8165139449225;997.8104160209537;1063.3513556730388;3995.1809878975323;4116.91766164675;4234.3860140279485;4267.741016400108;4273.594008569181;12577.748445031231;12985;13046.739065218642;13051.673708157372;13094.476220951055;12487.750244995232;12752.48990040388;13122.081454298108;13132.671683207996;13171;9643.324967252225;9698.272629552812;9730;9896.901030989742;9976.401415915068;6160.691965401622;6196.287426945779;6355.110284560258;6486.2216534016225;6501.739930402731;11601.941990289995;11904.226225295328;12047.397630118283;12070.75858482843;12197.878021219674;11683.707907302385;12141;12216.572419965258;12254.509819607116;12268.570600029314;7242.3843973260755;7468.477206595486;7928.603569821371;7953.32396746283;8281.710140145065;9848.803023939337;9940.950295248767;10057.899421005697;10069.942656021305;10073.345232559861;8511.531865747196;8512.06367299609;8550.957245213734;8589.44168629037;8622.698205562776;6634.701438435379;7761.340286075521;7882.211778822072;8512.361572882182;8617.48295102296;7620.885686714814;7651.617419129133;7710.11333696632;7728.304452599293;7729.768106956801;5607.8878422432135;5678.858028549319;5724.398938111438;5767.798127065533;5840.415958404125;5737;5800.709964501673;5848.6783226921225;5916.201312822714;5932.288125424977 07:53:15 INFO - PROCESS | 1916 | |4;prototype.html;316.2899291091499;318.2267090968946;372.9738918275722;380.08778930566655;384.64612556448066;4938.703677779331;5122.743862806863;5300.522952934235;5510;5552.77788888445;4670.346151538779;5470.152126420411;5480.369757477892;5501.284832971718;5532.723363831812;3868.4390763339297;3974.92676977216;4148;4184.196588285856;4486.596206341429;4314.266574682311;4707.1171531387545;4756.595689366408;4784.473707892129;4802.663813533054;4737.318252020535;4764.998051227722;4774.68964517307;4784.688995215317;4788.515560176339;4105.630493255607;4485.820567177309;4667.22990706533;4736.873663382185;4755.335632528588;4459.524963645175;4471.642268618502;4487.551244875519;4494.9212189074715;4513.503514613422;5344.064788662007;5356.9732151339385;5358.91961620576;5400.1919002119075;5420.539254163401;4791.664583479158;4839.516048395168;4957.752112394366;5266.78863861314;5379.327584051994;5908.320543137551;5943.583949123563;5957.851053723692;5968.955432799243;5996.970015149896;4046.6560342370935;4679.625629949596;5081.669691470042;5102.846914592568;5114.795408183668;5018.523240292203;5313.574914006909;5877.441643043947;6203.844903877394;6238.532110091761;4599.793009314589;5001.924971125436;5035.446100928895;5256.579473642099;5258.053550360895;4600.88497787558;5214.618126196561;5357.812476563302;5389.973050134724;5429.701366424805;4258.403823464718;5018.422881368616;5040.530140231261;5530.723463826793;5576.275084239104;5651.547876169979;5833.795817146464;5900.170947006356;5910.222195568076;5951.761929522857;1476.6086986948467;1587.6348439858666;1684.6125391159858;1692.6530061337642;1785.4732853808157;1198.6284251881943;1285.5115056278555;1287.620152055146;1294.7734146524322;1321.4978308242805;4854.514548545118;5177.197534382177;5228.686278823283;5254.6847189168775;5274.472552744694;5443.42844001382;5479.917801232985;5750.6262092963825;5820.476157145878;5838.620489668158;4808.942032752789;4950.381202349707;5019.820342219576;5079.644424890296;5229.267902493654;2508.5108403861072;2904.3880646241537;3019.667836537979;3044.2998110434282;3058.8317642529873;4949.925751113737;5059.822906198266;5161.793528258902;5751.884962300731;5876.353601103875;5502.394736578973;5725.2843394575675;6137.18952908893;6175.907361389583;6236.970899801573;5444.401115877171;6278.937210627927;6307.571584939831;6314.526410519229;6337.873242535124;5177.26280453396;5513.4562322549755;5704.229928959538;5717.599188198918;5953.493953014043;5061.342025536656;5883.882322353615;5947.1079338099635;6020.307664618625;6023.518118550418;5045.772940217773;6112.969435152796;6241.751649669994;6278.874422511615;6293.311482945235;4713.3636959011465;4745.857624271277;4747.5817411463695;4764.190087685171;4770.900803646385;5583.692896890548;5920.259967504062;5974.970125149346;6021.397860213943;6042.761233946942;4835.6131509479865;5305.60207984395;5780.219670344618;5813.709314534341;5871.240516957023;5299.788008479618;5591.980330621582;5751.1085781703905;5854.5609079320075;5876.823695289148;5153.484651534816;5648.4633959773755;5663.943360566342;5674.943250567441;5716.857078573069;5587.904535799075;5824.15549745293;5881.7647294109465;5892.970535147297;5921.319048309328;4905.754712264301;5346.584099081196;5712.400197979318;5783.132530120347;5814.307914474424 07:53:15 INFO - PROCESS | 1916 | |5;yui.html;645.3514218210703;662.9469642428606;750.8160500677335;825.640846231889;839.3704721458906;12522.686932826673;12802.93598532006;14780.96533242672;15964.600884977881;16116.677666446663;16121.677566448663;16369.345226190939;16383.4265800697;16550.337986480525;16604.83395166048;928.3919033033357;950.6672664567416;951.8381875081253;952.1620973543272;952.8951815300311;898.9235914947237;950.5769932380093;951.3150531617224;951.9095685909828;952.1525842000625;1002.0780881588919;1013.878334599849;1017;1017.2828156149897;1018.8828284747237;1009.6314845081492;1010.1867996263079;1013.6553571785591;1014.0417305646167;1014.7767491151935;742.4951033297356;743.7917383132677;745.5377665847155;745.6160077560006;746.0450623202255;15654.31185691952;15867.682646347008;15985;16047.277872495766;16424.507264781958;11752.647420577396;13763.793543096861;13970.231637259947;14031.368588413547;14061.296935153201;11460.71348216293;11666.358350290731;11676.1826672133;11774.352410617412;12366.19619724715;3615.191527915996;3666.926661466756;3668.5047518585184;3736.8692095776787;3794.5256842894637;98197.5090124552;110310;110980;112469;114915;10461.57503008342;10677.893221067845;10793.67618971432;10799.406032668121;11093.611723589796;22239.110435582395;22621.2082577109;22679.092836286363;22788.202412915467;22802.087916483488;7471.364933980563;7501.887471687929;7520.774376768596;7606.885896711554;7645.388368930472;4177.26897792885;4245.639120674778;4248.936265956013;4290.570942905684;4299.634531064895;3360.815155166489;3456.343294773985;3457.6542345765724;3464.9307013860084;3465.7227421806197;530.7691154347871;614.7387360371824;626.7680958045461;629.5561629051599;639.5139693832627;579.2383016333508;626.7211091064523;658.3219284137347;700.8214140974836;707.2598139728397;9181.678641247525;9407.284122771604;10568.841467377995;10775.946120269506;10968.341899486057;549.1296295371881;558.6146357034577;560.4133545310049;615.9014557670752;682.5938566552992;4398.6803958812225;4415.6909016368545;4543.727376357429;4656.883577910579;4854.2233242681;59424;69060;93535.53232233795;95164.52417738005;95821.52089239508;61221.69389153026;64467.74362897276;69712.6514367425;83066;86114.13885861062;72091;72502.63748681222;72997.17507062366;73368.798936032;73945.26054739491;10383.117435017806;10499.160067194452;10610.89389106099;10648.946755266174;10759.677209683721;47448.813779655786;49133;49825;49880.25179622236;50382.74808625934;63102.511880516904;64053.67973160104;64502;64701.67649161724;66864;12017.939910300393;12126.27242365461;12186.451609677762;12272.44773985155;12925.612231633066;13928.58214253574;14013.29933503341;14024.859751402355;14057;14315.355808988412;12240.449179787109;12380.690482738004;12382.257064575793;12604.11771176009;12652.114351995271;12098.334591597546;12508.749825003268;12658.620241392773;12669.8099528509;13180.604581862559;11936.940315298369;12202.75594488123;12310.07674424404;12334.75330493403;12797.8080328797;8553.529555874238;10245.692629221136;10282.331648442827;10666.679999600025;10924.672259832218;10297.588096476356;10535.841962370723;10536.473176341306;10549.314294570828;10621.946890265499 07:53:15 INFO - PROCESS | 1916 | __end_tp_report 07:53:15 INFO - PROCESS | 1916 | __start_cc_report 07:53:15 INFO - PROCESS | 1916 | _x_x_mozilla_cycle_collect,433 07:53:15 INFO - PROCESS | 1916 | __end_cc_report 07:53:15 INFO - PROCESS | 1916 | __startTimestamp1488383595428__endTimestamp 07:53:15 INFO - PROCESS | 1916 | 07:53:15 INFO - PROCESS | 1916 | ------- Summary: start ------- 07:53:15 INFO - PROCESS | 1916 | Number of tests: 6 07:53:15 INFO - PROCESS | 1916 | 07:53:15 INFO - PROCESS | 1916 | [#0] dojo.html Cycles:165 Average:5775.44 Median:6043.70 stddev:711.48 (11.8%) stddev-sans-first:674.83 07:53:15 INFO - PROCESS | 1916 | Values: 2820.0 2839.6 3385.7 3459.1 3461.8 5114.2 5785.3 5834.1 5878.7 5897.6 5762.1 5823.7 5824.0 5857.6 5933.2 5174.6 5240.8 5311.4 5330.9 5365.4 5394.4 5404.0 5404.9 5409.2 5418.1 5151.5 5185.8 5192.2 5261.8 5300.5 5213.7 5263.6 5270.2 5308.3 5347.0 5197.7 5227.1 5229.3 5240.2 5272.0 4226.8 4488.3 5580.6 5705.4 5783.9 6121.0 6189.6 6224.8 6239.6 6278.6 6326.4 6355.0 6372.6 6386.4 6393.9 5964.1 5972.1 6025.2 6040.0 6058.6 5216.3 5764.9 6286.2 6339.5 6459.9 5691.7 6139.0 6147.1 6174.8 6199.1 6152.1 6255.2 6260.8 6339.4 6364.3 6092.7 6251.3 6256.4 6271.4 6287.8 6137.8 6152.4 6237.0 6252.2 6270.0 4971.4 4988.1 4989.8 5002.3 5008.3 4008.9 4024.2 4413.5 4439.4 4578.2 5339.5 6011.3 6059.2 6068.0 6070.1 5803.9 6193.6 6303.6 6335.1 6336.4 4792.4 5792.3 5923.3 5956.8 6101.2 4917.5 5567.9 5576.9 5585.1 5590.2 6425.9 6435.7 6473.5 6493.8 6507.7 6149.3 6179.8 6216.3 6227.0 6248.6 6530.0 6536.2 6548.2 6559.8 6568.1 6272.5 6283.6 6312.1 6330.4 6450.9 6082.1 6150.1 6180.4 6207.0 6321.5 6113.5 6228.8 6247.7 6255.3 6290.2 5869.9 6032.1 6219.8 6252.8 6259.1 5843.8 5893.3 5964.8 6106.2 6108.8 5983.9 6263.9 6271.9 6279.9 6296.0 5885.4 6030.2 6043.7 6083.6 6160.9 07:53:15 INFO - PROCESS | 1916 | 07:53:15 INFO - PROCESS | 1916 | [#1] ext.html Cycles:180 Average:51516.38 Median:32732.84 stddev:101050.31 (308.7%) stddev-sans-first:101261.80 07:53:15 INFO - PROCESS | 1916 | Values: 715.3 782.5 804.3 936.2 938.5 32588.0 37240.6 37690.8 38627.4 39186.4 38065.2 38265.8 38306.0 38327.2 38409.8 7722.3 7843.3 10274.6 10729.1 10740.9 10562.7 10666.6 10670.1 10680.4 10684.8 10633.8 10661.0 10691.7 10695.5 10794.4 10605.0 10630.0 10659.7 10673.3 10685.1 10318.4 10350.4 10390.6 10393.8 10404.1 32560.5 32720.7 32745.0 32834.8 32947.7 3665.2 3676.2 3680.6 3681.6 3713.5 24822.3 24958.4 24980.3 25083.4 25528.0 2785.9 2854.8 2865.5 2873.8 2960.7 268024.0 332478.0 333144.0 336217.0 337922.0 36739.8 42370.4 43016.6 43023.0 43079.4 35804.3 45051.3 45119.1 45170.3 45174.3 12737.9 12745.1 12819.2 12830.0 12841.4 10794.9 10861.5 10879.1 10886.6 10893.6 8338.6 11559.8 11626.8 11663.0 11763.5 5444.6 5463.7 5473.1 5513.8 5515.7 805.6 969.2 984.8 991.3 1012.1 21174.0 23231.5 25379.6 28364.9 28707.6 1061.4 1069.6 1075.2 1104.6 1105.8 12993.5 14169.3 14529.0 14646.7 14808.4 403589.0 405178.0 405531.0 407026.0 408219.0 42356.4 42497.6 42619.4 42681.4 44511.6 1239.7 1387.9 1415.7 1426.8 1431.2 24874.1 29450.9 29881.7 29896.1 30049.0 42353.6 44921.0 45159.5 45301.8 45440.0 410466.0 410522.0 411324.0 411470.0 412032.0 20751.6 21407.7 23222.4 23457.0 23798.4 42476.8 43084.0 43656.8 43907.6 44028.7 30411.5 32925.3 35234.8 35243.1 36055.0 31938.0 32243.4 32608.8 33089.8 33161.8 32924.8 32986.5 40057.6 41183.4 41438.4 35941.0 38033.0 38212.0 38772.8 39970.0 26065.9 31448.8 31505.5 31954.4 32345.7 07:53:15 INFO - PROCESS | 1916 | 07:53:15 INFO - PROCESS | 1916 | [#2] jquery.html Cycles:180 Average:42444.03 Median:23983.08 stddev:122859.77 (512.3%) stddev-sans-first:123163.84 07:53:15 INFO - PROCESS | 1916 | Values: 387.9 388.2 398.5 417.4 427.1 13132.4 14609.0 19858.0 21104.3 21122.5 20531.9 21145.7 21183.2 21289.9 21491.8 6397.1 6782.3 7197.6 7267.2 7343.9 6246.8 7088.8 7162.9 7204.3 7232.7 7220.7 7225.2 7234.3 7241.8 7247.3 6160.0 6628.8 7198.4 7283.1 7284.1 6778.6 6996.6 7046.6 7061.0 7064.3 24650.6 25167.0 25229.1 25299.9 25368.9 2567.6 2899.8 3077.8 3336.5 3380.5 14337.9 15105.5 15473.2 15842.7 16049.4 1900.3 1975.8 2004.4 2030.5 2046.8 133520.0 143944.3 150383.0 155485.2 155812.0 23479.5 24486.6 24666.3 24716.5 24778.9 29743.9 40126.0 40384.4 40400.8 40761.6 8664.0 10462.7 10996.3 11176.2 11184.4 8725.6 8752.3 8796.8 8925.2 8938.5 11220.6 11521.8 11548.9 11722.1 11738.0 3744.3 4023.9 4265.3 4305.2 4360.9 811.0 904.0 1021.9 1036.8 1067.5 15535.5 17331.2 19630.8 20407.6 20565.7 759.5 884.6 920.2 934.2 935.5 10097.7 10373.0 10428.5 10469.3 10546.9 724996.0 725571.0 733497.0 739954.0 757479.0 124895.0 158971.2 161836.2 163143.8 165026.2 977.9 997.5 1018.6 1018.8 1021.2 16888.3 17005.4 17236.4 17443.3 17722.8 55471.7 59839.5 60360.0 61285.7 61322.0 67685.0 87758.0 89485.0 90564.0 90634.5 10683.1 10967.3 11149.9 11359.3 11392.0 12218.8 12421.3 12545.2 12648.5 12706.6 8630.1 8970.9 10442.3 10480.7 10999.0 9618.7 9895.6 10651.4 11040.4 11131.6 10804.6 10898.3 11032.9 11300.3 11375.1 9032.4 10988.3 11485.9 11763.1 11811.3 10800.5 11005.3 11063.4 11075.4 11181.7 07:53:15 INFO - PROCESS | 1916 | 07:53:15 INFO - PROCESS | 1916 | [#3] mootools.html Cycles:180 Average:6494.24 Median:3349.45 stddev:4085.53 (122.0%) stddev-sans-first:4078.68 07:53:15 INFO - PROCESS | 1916 | Values: 1347.3 1488.1 1500.6 1544.0 1547.2 9122.4 9411.6 10280.6 10922.0 10963.5 10183.2 10576.6 10590.8 10676.4 10761.6 2279.2 2330.7 2333.3 2336.8 2341.8 2299.6 2329.3 2342.9 2344.2 2350.1 2299.6 2316.7 2340.9 2342.4 2344.8 2277.2 2282.7 2305.4 2306.1 2308.4 1926.8 2267.9 2309.2 2311.8 2315.6 10563.4 10578.2 10652.6 10667.3 10672.1 5560.7 5720.6 5732.0 5742.9 5751.7 3129.9 3150.9 3303.0 3345.1 3353.8 1057.4 1079.3 1094.4 1110.4 1128.4 14914.8 14961.0 15071.0 15072.9 15098.0 4674.4 4729.6 4763.9 4773.8 4863.0 12252.0 12324.1 12330.6 12362.2 12382.7 5582.4 5599.4 5608.9 5661.7 5684.9 3534.4 4088.3 4943.9 5034.8 5078.4 3918.2 3935.3 3947.3 4059.1 4096.5 1116.1 1131.9 1143.3 1144.8 1148.6 762.5 802.2 832.2 849.1 867.2 6112.6 6340.2 6448.6 6702.4 6774.8 912.5 924.5 991.8 997.8 1063.4 3995.2 4116.9 4234.4 4267.7 4273.6 12577.7 12985.0 13046.7 13051.7 13094.5 12487.8 12752.5 13122.1 13132.7 13171.0 9643.3 9698.3 9730.0 9896.9 9976.4 6160.7 6196.3 6355.1 6486.2 6501.7 11601.9 11904.2 12047.4 12070.8 12197.9 11683.7 12141.0 12216.6 12254.5 12268.6 7242.4 7468.5 7928.6 7953.3 8281.7 9848.8 9941.0 10057.9 10069.9 10073.3 8511.5 8512.1 8551.0 8589.4 8622.7 6634.7 7761.3 7882.2 8512.4 8617.5 7620.9 7651.6 7710.1 7728.3 7729.8 5607.9 5678.9 5724.4 5767.8 5840.4 5737.0 5800.7 5848.7 5916.2 5932.3 07:53:15 INFO - PROCESS | 1916 | 07:53:15 INFO - PROCESS | 1916 | [#4] prototype.html Cycles:180 Average:4914.91 Median:5270.63 stddev:1346.61 (25.5%) stddev-sans-first:1305.40 07:53:15 INFO - PROCESS | 1916 | Values: 316.3 318.2 373.0 380.1 384.6 4938.7 5122.7 5300.5 5510.0 5552.8 4670.3 5470.2 5480.4 5501.3 5532.7 3868.4 3974.9 4148.0 4184.2 4486.6 4314.3 4707.1 4756.6 4784.5 4802.7 4737.3 4765.0 4774.7 4784.7 4788.5 4105.6 4485.8 4667.2 4736.9 4755.3 4459.5 4471.6 4487.6 4494.9 4513.5 5344.1 5357.0 5358.9 5400.2 5420.5 4791.7 4839.5 4957.8 5266.8 5379.3 5908.3 5943.6 5957.9 5969.0 5997.0 4046.7 4679.6 5081.7 5102.8 5114.8 5018.5 5313.6 5877.4 6203.8 6238.5 4599.8 5001.9 5035.4 5256.6 5258.1 4600.9 5214.6 5357.8 5390.0 5429.7 4258.4 5018.4 5040.5 5530.7 5576.3 5651.5 5833.8 5900.2 5910.2 5951.8 1476.6 1587.6 1684.6 1692.7 1785.5 1198.6 1285.5 1287.6 1294.8 1321.5 4854.5 5177.2 5228.7 5254.7 5274.5 5443.4 5479.9 5750.6 5820.5 5838.6 4808.9 4950.4 5019.8 5079.6 5229.3 2508.5 2904.4 3019.7 3044.3 3058.8 4949.9 5059.8 5161.8 5751.9 5876.4 5502.4 5725.3 6137.2 6175.9 6237.0 5444.4 6278.9 6307.6 6314.5 6337.9 5177.3 5513.5 5704.2 5717.6 5953.5 5061.3 5883.9 5947.1 6020.3 6023.5 5045.8 6113.0 6241.8 6278.9 6293.3 4713.4 4745.9 4747.6 4764.2 4770.9 5583.7 5920.3 5975.0 6021.4 6042.8 4835.6 5305.6 5780.2 5813.7 5871.2 5299.8 5592.0 5751.1 5854.6 5876.8 5153.5 5648.5 5663.9 5674.9 5716.9 5587.9 5824.2 5881.8 5893.0 5921.3 4905.8 5346.6 5712.4 5783.1 5814.3 07:53:15 INFO - PROCESS | 1916 | 07:53:15 INFO - PROCESS | 1916 | [#5] yui.html Cycles:180 Average:19259.12 Median:13081.45 stddev:27010.61 (206.5%) stddev-sans-first:27050.22 07:53:15 INFO - PROCESS | 1916 | Values: 645.4 662.9 750.8 825.6 839.4 12522.7 12802.9 14781.0 15964.6 16116.7 16121.7 16369.3 16383.4 16550.3 16604.8 928.4 950.7 951.8 952.2 952.9 898.9 950.6 951.3 951.9 952.2 1002.1 1013.9 1017.0 1017.3 1018.9 1009.6 1010.2 1013.7 1014.0 1014.8 742.5 743.8 745.5 745.6 746.0 15654.3 15867.7 15985.0 16047.3 16424.5 11752.6 13763.8 13970.2 14031.4 14061.3 11460.7 11666.4 11676.2 11774.4 12366.2 3615.2 3666.9 3668.5 3736.9 3794.5 98197.5 110310.0 110980.0 112469.0 114915.0 10461.6 10677.9 10793.7 10799.4 11093.6 22239.1 22621.2 22679.1 22788.2 22802.1 7471.4 7501.9 7520.8 7606.9 7645.4 4177.3 4245.6 4248.9 4290.6 4299.6 3360.8 3456.3 3457.7 3464.9 3465.7 530.8 614.7 626.8 629.6 639.5 579.2 626.7 658.3 700.8 707.3 9181.7 9407.3 10568.8 10775.9 10968.3 549.1 558.6 560.4 615.9 682.6 4398.7 4415.7 4543.7 4656.9 4854.2 59424.0 69060.0 93535.5 95164.5 95821.5 61221.7 64467.7 69712.7 83066.0 86114.1 72091.0 72502.6 72997.2 73368.8 73945.3 10383.1 10499.2 10610.9 10648.9 10759.7 47448.8 49133.0 49825.0 49880.3 50382.7 63102.5 64053.7 64502.0 64701.7 66864.0 12017.9 12126.3 12186.5 12272.4 12925.6 13928.6 14013.3 14024.9 14057.0 14315.4 12240.4 12380.7 12382.3 12604.1 12652.1 12098.3 12508.7 12658.6 12669.8 13180.6 11936.9 12202.8 12310.1 12334.8 12797.8 8553.5 10245.7 10282.3 10666.7 10924.7 10297.6 10535.8 10536.5 10549.3 10621.9 07:53:15 INFO - PROCESS | 1916 | -------- Summary: end -------- 07:53:15 INFO - PROCESS | 1916 | 07:53:15 INFO - PROCESS | 1916 | 1488383595454 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 07:53:16 INFO - TEST-INFO | 1916: exit 0 07:53:16 INFO - TEST-OK | dromaeo_css | took 1117118ms 07:53:16 INFO - TEST-START | kraken 07:53:16 INFO - operating with platform_type : mac_ 07:53:16 INFO - Initialising browser for kraken test... 07:53:16 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpVb7aEs/profile http://localhost:49367/getInfo.html) 07:53:16 INFO - PROCESS | 1920 | 1488383596653 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 07:53:16 INFO - PROCESS | 1920 | 1488383596654 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 07:53:16 INFO - PROCESS | 1920 | 1488383596694 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 07:53:19 INFO - PROCESS | 1920 | 2017-03-01 07:53:19.073 plugin-container[1921:19313] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 07:53:19 INFO - PROCESS | 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:53:19 INFO - PROCESS | 1920 | 2017-03-01 07:53:19.076 plugin-container[1921:19313] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f13, name = 'com.apple.CFPasteboardClient' 07:53:19 INFO - PROCESS | 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:53:19 INFO - PROCESS | 1920 | 2017-03-01 07:53:19.076 plugin-container[1921:19313] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:53:19 INFO - PROCESS | 1920 | __metrics Screen width/height:1600/1200 07:53:19 INFO - PROCESS | 1920 | colorDepth:24 07:53:19 INFO - PROCESS | 1920 | Browser inner width/height: 1024/663 07:53:19 INFO - PROCESS | 1920 | __metrics 07:53:19 ERROR - PROCESS | 1920 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 07:53:19 INFO - PROCESS | 1920 | 1488383599277 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 07:53:19 INFO - TEST-INFO | 1920: exit 0 07:53:19 INFO - Browser initialized. 07:53:19 INFO - Running cycle 1/1 for kraken test... 07:53:19 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpVb7aEs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:53:20 INFO - PROCESS | 1922 | 1488383600347 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 07:53:20 INFO - PROCESS | 1922 | 1488383600348 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 07:53:22 INFO - PROCESS | 1922 | 2017-03-01 07:53:22.593 plugin-container[1923:19436] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 07:53:22 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:53:22 INFO - PROCESS | 1922 | 2017-03-01 07:53:22.593 plugin-container[1923:19436] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 07:53:22 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:53:22 INFO - PROCESS | 1922 | 2017-03-01 07:53:22.593 plugin-container[1923:19436] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:53:32 INFO - PROCESS | 1922 | RSS: Main: 236253184 07:53:32 INFO - PROCESS | 1922 | 07:53:35 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49367/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49367/tests/kraken/driver.html?testName=audio-beat-detection) 07:53:36 INFO - PROCESS | 1922 | RSS: Main: 248389632 07:53:36 INFO - PROCESS | 1922 | 07:53:39 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49367/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49367/tests/kraken/driver.html?testName=audio-dft) 07:53:39 INFO - PROCESS | 1922 | RSS: Main: 256159744 07:53:39 INFO - PROCESS | 1922 | 07:53:43 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49367/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49367/tests/kraken/driver.html?testName=audio-fft) 07:53:43 INFO - PROCESS | 1922 | RSS: Main: 255950848 07:53:43 INFO - PROCESS | 1922 | 07:53:45 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49367/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49367/tests/kraken/driver.html?testName=audio-oscillator) 07:53:46 INFO - PROCESS | 1922 | RSS: Main: 256643072 07:53:46 INFO - PROCESS | 1922 | 07:53:48 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49367/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49367/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:53:49 INFO - PROCESS | 1922 | RSS: Main: 258183168 07:53:49 INFO - PROCESS | 1922 | 07:53:52 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49367/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49367/tests/kraken/driver.html?testName=imaging-darkroom) 07:53:53 INFO - PROCESS | 1922 | RSS: Main: 263536640 07:53:53 INFO - PROCESS | 1922 | 07:53:56 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49367/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49367/tests/kraken/driver.html?testName=imaging-desaturate) 07:53:56 INFO - PROCESS | 1922 | RSS: Main: 263180288 07:53:56 INFO - PROCESS | 1922 | 07:53:59 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49367/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49367/tests/kraken/driver.html?testName=json-parse-financial) 07:54:00 INFO - PROCESS | 1922 | RSS: Main: 263049216 07:54:00 INFO - PROCESS | 1922 | 07:54:02 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49367/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49367/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:54:02 INFO - PROCESS | 1922 | RSS: Main: 262467584 07:54:02 INFO - PROCESS | 1922 | 07:54:04 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49367/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49367/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:54:05 INFO - PROCESS | 1922 | RSS: Main: 262479872 07:54:05 INFO - PROCESS | 1922 | 07:54:07 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49367/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49367/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:54:08 INFO - PROCESS | 1922 | RSS: Main: 262549504 07:54:08 INFO - PROCESS | 1922 | 07:54:11 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49367/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49367/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:54:12 INFO - PROCESS | 1922 | RSS: Main: 262983680 07:54:12 INFO - PROCESS | 1922 | 07:54:15 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49367/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49367/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:54:15 INFO - PROCESS | 1922 | RSS: Main: 262488064 07:54:15 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49367/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49367/tests/kraken/driver.html?testName=ai-astar) 07:54:17 INFO - PROCESS | 1922 | RSS: Main: 265895936 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | __start_tp_report 07:54:17 INFO - PROCESS | 1922 | _x_x_mozilla_page_load 07:54:17 INFO - PROCESS | 1922 | _x_x_mozilla_page_load_details 07:54:17 INFO - PROCESS | 1922 | |i|pagename|runs| 07:54:17 INFO - PROCESS | 1922 | |0;ai-astar;108;119;119;120;119;137;182;131;118;119 07:54:17 INFO - PROCESS | 1922 | |1;audio-beat-detection;133;117;133;184;141;115;111;114;137;114 07:54:17 INFO - PROCESS | 1922 | |2;audio-dft;209;204;220;201;218;293;210;203;195;200 07:54:17 INFO - PROCESS | 1922 | |3;audio-fft;82;61;62;87;59;60;61;62;77;52 07:54:17 INFO - PROCESS | 1922 | |4;audio-oscillator;85;71;84;74;72;76;114;101;84;83 07:54:17 INFO - PROCESS | 1922 | |5;imaging-gaussian-blur;104;89;105;88;94;90;98;111;138;105 07:54:17 INFO - PROCESS | 1922 | |6;imaging-darkroom;96;97;121;111;96;98;97;98;107;98 07:54:17 INFO - PROCESS | 1922 | |7;imaging-desaturate;91;93;91;91;91;89;91;92;90;92 07:54:17 INFO - PROCESS | 1922 | |8;json-parse-financial;66;67;64;68;68;68;66;67;68;66 07:54:17 INFO - PROCESS | 1922 | |9;json-stringify-tinderbox;58;63;59;71;65;65;61;60;58;59 07:54:17 INFO - PROCESS | 1922 | |10;stanford-crypto-aes;111;80;99;107;76;103;108;107;107;84 07:54:17 INFO - PROCESS | 1922 | |11;stanford-crypto-ccm;150;164;141;145;142;145;168;153;212;161 07:54:17 INFO - PROCESS | 1922 | |12;stanford-crypto-pbkdf2;174;178;175;177;174;176;177;175;175;172 07:54:17 INFO - PROCESS | 1922 | |13;stanford-crypto-sha256-iterative;71;67;73;86;93;91;87;93;77;73 07:54:17 INFO - PROCESS | 1922 | __end_tp_report 07:54:17 INFO - PROCESS | 1922 | __start_cc_report 07:54:17 INFO - PROCESS | 1922 | _x_x_mozilla_cycle_collect,1023 07:54:17 INFO - PROCESS | 1922 | __end_cc_report 07:54:17 INFO - PROCESS | 1922 | __startTimestamp1488383657814__endTimestamp 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | ------- Summary: start ------- 07:54:17 INFO - PROCESS | 1922 | Number of tests: 14 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | [#0] ai-astar Cycles:10 Average:127.20 Median:119.50 stddev:20.78 (17.4%) stddev-sans-first:20.85 07:54:17 INFO - PROCESS | 1922 | Values: 108.0 119.0 119.0 120.0 119.0 137.0 182.0 131.0 118.0 119.0 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:133.00 stddev:22.04 (16.6%) stddev-sans-first:23.35 07:54:17 INFO - PROCESS | 1922 | Values: 133.0 117.0 133.0 184.0 141.0 115.0 111.0 114.0 137.0 114.0 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | [#2] audio-dft Cycles:10 Average:215.30 Median:209.50 stddev:28.41 (13.6%) stddev-sans-first:30.04 07:54:17 INFO - PROCESS | 1922 | Values: 209.0 204.0 220.0 201.0 218.0 293.0 210.0 203.0 195.0 200.0 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | [#3] audio-fft Cycles:10 Average:66.30 Median:62.00 stddev:11.45 (18.5%) stddev-sans-first:10.64 07:54:17 INFO - PROCESS | 1922 | Values: 82.0 61.0 62.0 87.0 59.0 60.0 61.0 62.0 77.0 52.0 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | [#4] audio-oscillator Cycles:10 Average:84.40 Median:79.50 stddev:13.61 (17.1%) stddev-sans-first:14.43 07:54:17 INFO - PROCESS | 1922 | Values: 85.0 71.0 84.0 74.0 72.0 76.0 114.0 101.0 84.0 83.0 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | [#5] imaging-gaussian-blur Cycles:10 Average:102.20 Median:88.50 stddev:14.86 (16.8%) stddev-sans-first:15.75 07:54:17 INFO - PROCESS | 1922 | Values: 104.0 89.0 105.0 88.0 94.0 90.0 98.0 111.0 138.0 105.0 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | [#6] imaging-darkroom Cycles:10 Average:101.90 Median:97.00 stddev:8.41 (8.7%) stddev-sans-first:8.65 07:54:17 INFO - PROCESS | 1922 | Values: 96.0 97.0 121.0 111.0 96.0 98.0 97.0 98.0 107.0 98.0 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | [#7] imaging-desaturate Cycles:10 Average:91.10 Median:91.00 stddev:1.10 (1.2%) stddev-sans-first:1.17 07:54:17 INFO - PROCESS | 1922 | Values: 91.0 93.0 91.0 91.0 91.0 89.0 91.0 92.0 90.0 92.0 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:67.50 stddev:1.32 (2.0%) stddev-sans-first:1.36 07:54:17 INFO - PROCESS | 1922 | Values: 66.0 67.0 64.0 68.0 68.0 68.0 66.0 67.0 68.0 66.0 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | [#9] json-stringify-tinderbox Cycles:10 Average:61.90 Median:62.00 stddev:4.15 (6.7%) stddev-sans-first:4.15 07:54:17 INFO - PROCESS | 1922 | Values: 58.0 63.0 59.0 71.0 65.0 65.0 61.0 60.0 58.0 59.0 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | [#10] stanford-crypto-aes Cycles:10 Average:98.20 Median:93.50 stddev:13.09 (14.0%) stddev-sans-first:13.04 07:54:17 INFO - PROCESS | 1922 | Values: 111.0 80.0 99.0 107.0 76.0 103.0 108.0 107.0 107.0 84.0 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | [#11] stanford-crypto-ccm Cycles:10 Average:158.10 Median:157.00 stddev:21.17 (13.5%) stddev-sans-first:22.25 07:54:17 INFO - PROCESS | 1922 | Values: 150.0 164.0 141.0 145.0 142.0 145.0 168.0 153.0 212.0 161.0 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.30 Median:175.50 stddev:1.77 (1.0%) stddev-sans-first:1.81 07:54:17 INFO - PROCESS | 1922 | Values: 174.0 178.0 175.0 177.0 174.0 176.0 177.0 175.0 175.0 172.0 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.10 Median:86.50 stddev:9.94 (11.5%) stddev-sans-first:9.85 07:54:17 INFO - PROCESS | 1922 | Values: 71.0 67.0 73.0 86.0 93.0 91.0 87.0 93.0 77.0 73.0 07:54:17 INFO - PROCESS | 1922 | -------- Summary: end -------- 07:54:17 INFO - PROCESS | 1922 | 07:54:17 INFO - PROCESS | 1922 | 1488383657837 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 07:54:18 INFO - TEST-INFO | 1922: exit 0 07:54:18 INFO - TEST-OK | kraken | took 62577ms 07:54:18 INFO - SUITE-END | took 1179s 07:54:18 INFO - Completed test suite (00:19:39) 07:54:18 INFO - javascript benchmark 07:54:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7607.379817676603, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2820.012995451591, 2839.602455656208, 3385.7291416686667, 3459.0833429141267, 3461.757676962612, 5114.207297868837, 5785.3346865110525, 5834.124881267807, 5878.706064696758, 5897.616654917433, 5762.078067509199, 5823.650580965134, 5823.951688696044, 5857.6485410875375, 5933.199018132551, 5174.560162386202, 5240.790368385269, 5311.442298558653, 5330.893382132355, 5365.356157261134, 5394.352677678684, 5404.0, 5404.865078294071, 5409.215663728757, 5418.078926582471, 5151.536361727443, 5185.792568297264, 5192.221166824968, 5261.789528418859, 5300.549453296474, 5213.661112027744, 5263.605229607795, 5270.156774916033, 5308.309919710451, 5347.037533244014, 5197.688138711689, 5227.085260079471, 5229.346331708536, 5240.187770895517, 5271.973640131813, 4226.788660566959, 4488.259437192859, 5580.637258578179, 5705.372409035002, 5783.913241301384, 6121.026213050743, 6189.566730328879, 6224.813255602339, 6239.563230573862, 6278.560500764948, 6326.367363263683, 6355.014972679242, 6372.5857819242065, 6386.361363863576, 6393.913034784051, 5964.105384192319, 5972.134040564094, 6025.216721826135, 6039.999999999956, 6058.575899687068, 5216.347956505437, 5764.913526297109, 6286.151369565142, 6339.492840572743, 6459.935400646027, 5691.68695721731, 6139.032198454786, 6147.108669242934, 6174.8147555573405, 6199.1011303361665, 6152.107944348134, 6255.218097737783, 6260.780872669435, 6339.397757213057, 6364.331745166784, 6092.695365231721, 6251.281102673251, 6256.43692067707, 6271.43557079865, 6287.811365659038, 6137.815865524041, 6152.446279834837, 6236.968815155895, 6252.249730032334, 6269.96865015672, 4971.403431588233, 4988.102141614471, 4989.750512474435, 5002.299678045077, 5008.323876276656, 4008.8797336079965, 4024.1951609677594, 4413.536578659258, 4439.356293337448, 4578.244589642738, 5339.492748188994, 6011.3086994995265, 6059.212302400748, 6067.998780201306, 6070.0591408331775, 5803.8549036274435, 6193.628382297076, 6303.558750887394, 6335.11308416822, 6336.429721325103, 4792.424909010941, 5792.333881603669, 5923.289205295392, 5956.791512297049, 6101.176341193882, 4917.459079501252, 5567.888642227133, 5576.860578485489, 5585.050541407971, 5590.2453168821685, 6425.935740642627, 6435.678216089083, 6473.48212143018, 6493.772717954849, 6507.674616269168, 6149.2620885494025, 6179.81460556184, 6216.347283535164, 6226.968865155645, 6248.562600617913, 6529.967350163219, 6536.182977127859, 6548.1814773153355, 6559.836004099936, 6568.146141001639, 6272.498200143887, 6283.622982621057, 6312.147860038836, 6330.39861213184, 6450.903236451551, 6082.118092876596, 6150.077488376601, 6180.412860778219, 6207.0, 6321.462675672619, 6113.5414843886, 6228.750849965951, 6247.687615619292, 6255.280642726145, 6290.150829637941, 5869.882602348014, 6032.065029920369, 6219.844503887439, 6252.781152659635, 6259.061140828912, 5843.766249349978, 5893.322267939242, 5964.7912323068485, 6106.236720409949, 6108.786192483241, 5983.892979798751, 6263.874722505433, 6271.905921411091, 6279.905801413074, 6296.0, 5885.411458854252, 6030.216071910623, 6043.697815109139, 6083.6349819011, 6160.876782464416], "value": 5726.8142509832, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.2525610736783, 782.4992005116725, 804.3283857978588, 936.1855185988192, 938.5025936253779, 32588.0, 37240.62759372406, 37690.81154594227, 38627.420588691195, 39186.412203816966, 38065.23869522608, 38265.808670956605, 38306.04234894129, 38327.23345533095, 38409.807950960276, 7722.343600793925, 7843.333316668061, 10274.589016439333, 10729.141668666489, 10740.946295268513, 10562.735931601734, 10666.573337066508, 10670.093042091432, 10680.359178449278, 10684.78630427391, 10633.78732425351, 10661.040506354426, 10691.679249622484, 10695.46522673867, 10794.406307653077, 10605.045545900868, 10630.04329610335, 10659.733506662318, 10673.252872298865, 10685.091767199758, 10318.432486213254, 10350.378977261389, 10390.636327728569, 10393.792124157475, 10404.115650169744, 32560.511592326133, 32720.672793272002, 32745.0, 32834.835825820956, 32947.68209271626, 3665.2303016366595, 3676.209614932802, 3680.595134535199, 3681.5582130144285, 3713.4801127842125, 24822.255332340057, 24958.37604059895, 24980.250592482254, 25083.372915677253, 25528.0, 2785.9047200615064, 2854.785891058158, 2865.498537755905, 2873.841938693371, 2960.689127641588, 268023.999999998, 332478.0, 333144.0, 336217.0, 337922.0, 36739.81630091886, 42370.364444533356, 43016.569834301255, 43023.0, 43079.35380969288, 35804.28391432157, 45051.324230136575, 45119.09761804811, 45170.32244516335, 45174.28954262689, 12737.93631031839, 12745.107842451125, 12819.16675416095, 12830.037747168999, 12841.357932103358, 10794.946025269981, 10861.45692715377, 10879.12966962641, 10886.564537418415, 10893.564257429773, 8338.583070846433, 11559.826602600968, 11626.76746465066, 11662.950334469939, 11763.470643821049, 5444.5916556258435, 5463.672179669233, 5473.0969390050195, 5513.751881165277, 5515.696636685021, 805.6092794994423, 969.1955676788249, 984.7538115471132, 991.3358050106445, 1012.0587853296357, 21174.0, 23231.53536929252, 25379.61930571043, 28364.85817570899, 28707.56938645922, 1061.4215252687304, 1069.6042464288264, 1075.1828610256105, 1104.629948967105, 1105.784372047443, 12993.545225917047, 14169.291535423188, 14529.0, 14646.714300693404, 14808.407663693331, 403589.0, 405178.0, 405531.0, 407026.0, 408219.0, 42356.3646545302, 42497.57502424936, 42619.36070958938, 42681.35977960333, 44511.55488445139, 1239.6528971887885, 1387.854275301099, 1415.6712208417096, 1426.8287805463203, 1431.169921445559, 24874.12940547072, 29450.915972219063, 29881.70118298789, 29896.115807943606, 30049.0, 42353.57646423496, 44921.0, 45159.54840451553, 45301.773491132335, 45440.0, 410466.0, 410522.0, 411324.0, 411470.0, 412032.0, 20751.584968300853, 21407.67888481705, 23222.419439514146, 23457.0, 23798.405039874142, 42476.78761606172, 43084.0, 43656.78171609122, 43907.560924390345, 44028.67913962586, 30411.54382684217, 32925.34149317048, 35234.8238258807, 35243.11892202613, 36055.0, 31938.0, 32243.35513289768, 32608.83695581602, 33089.834550827094, 33161.83419082889, 32924.835375822964, 32986.505202421504, 40057.599424006556, 41183.382249265684, 41438.37842432426, 35941.0, 38033.0, 38212.0, 38772.80613596914, 39970.0, 26065.869670651526, 31448.842755786074, 31505.527417088302, 31954.36091278208, 32345.706171752867], "value": 17933.227872260126, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.901085223268, 388.18674876134526, 398.5197836606889, 417.38852580968876, 427.08816676395907, 13132.409041593128, 14609.0, 19858.0, 21104.261350852725, 21122.471938201554, 20531.89734051328, 21145.731256124644, 21183.152673893026, 21289.893550532266, 21491.809097226047, 6397.104405383238, 6782.287859774702, 7197.640117994079, 7267.200607933123, 7343.88984165238, 6246.843828904291, 7088.8303429934, 7162.892556611629, 7204.279572042807, 7232.746853860116, 7220.67506962188, 7225.205227424979, 7234.2765723427765, 7241.782746517587, 7247.3477387035155, 6160.045192995093, 6628.834279143011, 7198.388137008386, 7283.053203083618, 7284.125904891394, 6778.559393639447, 6996.580205187705, 7046.577205367695, 7061.0, 7064.328888755612, 24650.630240546405, 25167.0, 25229.11698090558, 25299.873500632562, 25368.87315563429, 2567.562204849401, 2899.8260104393803, 3077.7845550811453, 3336.4828451590042, 3380.509826075205, 14337.928310358382, 15105.546833594899, 15473.226338683133, 15842.683146337124, 16049.438269660506, 1900.3348827910258, 1975.7530308711412, 2004.3586052463218, 2030.522827135622, 2046.7646220684662, 133520.0, 143944.28027859793, 150383.0, 155485.22257388753, 155811.99999999886, 23479.530409391715, 24486.632700509508, 24666.260012199662, 24716.5056698865, 24778.876105619358, 29743.85128074389, 40126.0, 40384.39423408651, 40400.79799600983, 40761.59238407578, 8664.0, 10462.68611941643, 10996.340219586851, 11176.217664763388, 11184.38485883268, 8725.563721813884, 8752.299816014704, 8796.78008049791, 8925.241354484811, 8938.50838203892, 11220.55117795279, 11521.779277368165, 11548.884511154949, 11722.120840936965, 11738.0, 3744.2776322891773, 4023.8591649292134, 4265.274903266452, 4305.225059489259, 4360.890286248688, 810.9675612975415, 903.9819203615891, 1021.9437930913871, 1036.8081904847477, 1067.546292825546, 15535.533933981773, 17331.180226076332, 19630.803691962898, 20407.591848162952, 20565.69151462729, 759.502525845572, 884.6284560484612, 920.2362039507193, 934.2385955446425, 935.5322338830584, 10097.697069087939, 10373.0, 10428.478576071318, 10469.267151299337, 10546.947265263778, 724996.001599354, 725571.0, 733497.0, 739954.0, 757479.0, 124895.0, 158971.20514397355, 161836.19081904515, 163143.84280785912, 165026.1748691249, 977.9413235205768, 997.5112195024386, 1018.6485662446444, 1018.7707765752647, 1021.2034613001871, 16888.324467021182, 17005.404810831562, 17236.396726114526, 17443.30226790914, 17722.822771772633, 55471.7301856323, 59839.50401240004, 60360.0, 61285.693571533644, 61322.0, 67685.0, 87758.0, 89485.0, 90564.0, 90634.54682726544, 10683.145348372267, 10967.335249241713, 11149.888501115209, 11359.261647992855, 11392.0, 12218.816717749063, 12421.254724716546, 12545.184563003375, 12648.494060237488, 12706.618801435972, 8630.058236896648, 8970.865437018581, 10442.321249118782, 10480.737981550523, 10999.040345475463, 9618.749562556823, 9895.604175832887, 10651.414172220602, 11040.447977600927, 11131.55473781072, 10804.621838235624, 10898.291611045257, 11032.889671103187, 11300.265482743594, 11375.1468639854, 9032.36773425854, 10988.285761425483, 11485.885141148481, 11763.117766167743, 11811.291322520676, 10800.472911885401, 11005.284656497302, 11063.391513466837, 11075.446227688422, 11181.664550063511], "value": 11430.83584493546, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.299404309759, 1488.0625206120142, 1500.6248437890533, 1543.9922800385996, 1547.2418514927685, 9122.407043542165, 9411.623535058607, 10280.640177593787, 10921.99999999999, 10963.451827408628, 10183.236257280714, 10576.576936922533, 10590.841137382946, 10676.359418434899, 10761.569537218522, 2279.1567120165564, 2330.673705681206, 2333.323336232499, 2336.8013718833918, 2341.7892389684926, 2299.5630830142304, 2329.26628112145, 2342.8711420871846, 2344.2498400511845, 2350.106959355451, 2299.620562607168, 2316.7104111986, 2340.8829558522093, 2342.355852140658, 2344.800691941187, 2277.20297895736, 2282.6971159364716, 2305.3775480620225, 2306.1467257114973, 2308.4344335637707, 1926.7572415791876, 2267.8979445924974, 2309.21486694523, 2311.7572654871333, 2315.560043591729, 10563.366198028143, 10578.206634502443, 10652.62715804943, 10667.253292269545, 10672.146228301717, 5560.6941618210985, 5720.599558030939, 5731.971340143315, 5742.942570574324, 5751.734618383949, 3129.874805007797, 3150.936981260385, 3302.9669703302898, 3345.080102971678, 3353.815540145291, 1057.370864335719, 1079.3146352066417, 1094.3543309447464, 1110.3615421132881, 1128.3793913347627, 14914.776278355834, 14960.952733308564, 15071.020383674917, 15072.924635376863, 15098.018628789203, 4674.392328997209, 4729.624130655579, 4763.928116173903, 4773.832915847928, 4862.9756851216225, 12252.0, 12324.07569432296, 12330.63008109758, 12362.196457230251, 12382.690432739073, 5582.358028826656, 5599.412061733541, 5608.915866262009, 5661.745221464962, 5684.914726279109, 3534.434490481562, 4088.2845502037026, 4943.8516844494725, 5034.798608055637, 5078.4413714491375, 3918.2163567286657, 3935.2719746846924, 3947.289487892207, 4059.127287633173, 4096.508418989741, 1116.1015382617072, 1131.8754936956927, 1143.3425780176265, 1144.811106167473, 1148.586508856811, 762.4891322813728, 802.2058162419163, 832.2177153475714, 849.0617867256592, 867.2238346679686, 6112.633242005494, 6340.239171299381, 6448.645324507103, 6702.36327548882, 6774.796756097325, 912.4753266871576, 924.4822899176482, 991.8165139449225, 997.8104160209537, 1063.3513556730388, 3995.1809878975323, 4116.91766164675, 4234.3860140279485, 4267.741016400108, 4273.594008569181, 12577.748445031231, 12985.0, 13046.739065218642, 13051.673708157372, 13094.476220951055, 12487.750244995232, 12752.48990040388, 13122.081454298108, 13132.671683207996, 13171.0, 9643.324967252225, 9698.272629552812, 9730.0, 9896.901030989742, 9976.401415915068, 6160.691965401622, 6196.287426945779, 6355.110284560258, 6486.2216534016225, 6501.739930402731, 11601.941990289995, 11904.226225295328, 12047.397630118283, 12070.75858482843, 12197.878021219674, 11683.707907302385, 12141.0, 12216.572419965258, 12254.509819607116, 12268.570600029314, 7242.3843973260755, 7468.477206595486, 7928.603569821371, 7953.32396746283, 8281.710140145065, 9848.803023939337, 9940.950295248767, 10057.899421005697, 10069.942656021305, 10073.345232559861, 8511.531865747196, 8512.06367299609, 8550.957245213734, 8589.44168629037, 8622.698205562776, 6634.701438435379, 7761.340286075521, 7882.211778822072, 8512.361572882182, 8617.48295102296, 7620.885686714814, 7651.617419129133, 7710.11333696632, 7728.304452599293, 7729.768106956801, 5607.8878422432135, 5678.858028549319, 5724.398938111438, 5767.798127065533, 5840.415958404125, 5737.0, 5800.709964501673, 5848.6783226921225, 5916.201312822714, 5932.288125424977], "value": 4948.8045588377345, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.2899291091499, 318.2267090968946, 372.9738918275722, 380.08778930566655, 384.64612556448066, 4938.703677779331, 5122.743862806863, 5300.522952934235, 5510.0, 5552.77788888445, 4670.346151538779, 5470.152126420411, 5480.369757477892, 5501.284832971718, 5532.723363831812, 3868.4390763339297, 3974.92676977216, 4148.0, 4184.196588285856, 4486.596206341429, 4314.266574682311, 4707.1171531387545, 4756.595689366408, 4784.473707892129, 4802.663813533054, 4737.318252020535, 4764.998051227722, 4774.68964517307, 4784.688995215317, 4788.515560176339, 4105.630493255607, 4485.820567177309, 4667.22990706533, 4736.873663382185, 4755.335632528588, 4459.524963645175, 4471.642268618502, 4487.551244875519, 4494.9212189074715, 4513.503514613422, 5344.064788662007, 5356.9732151339385, 5358.91961620576, 5400.1919002119075, 5420.539254163401, 4791.664583479158, 4839.516048395168, 4957.752112394366, 5266.78863861314, 5379.327584051994, 5908.320543137551, 5943.583949123563, 5957.851053723692, 5968.955432799243, 5996.970015149896, 4046.6560342370935, 4679.625629949596, 5081.669691470042, 5102.846914592568, 5114.795408183668, 5018.523240292203, 5313.574914006909, 5877.441643043947, 6203.844903877394, 6238.532110091761, 4599.793009314589, 5001.924971125436, 5035.446100928895, 5256.579473642099, 5258.053550360895, 4600.88497787558, 5214.618126196561, 5357.812476563302, 5389.973050134724, 5429.701366424805, 4258.403823464718, 5018.422881368616, 5040.530140231261, 5530.723463826793, 5576.275084239104, 5651.547876169979, 5833.795817146464, 5900.170947006356, 5910.222195568076, 5951.761929522857, 1476.6086986948467, 1587.6348439858666, 1684.6125391159858, 1692.6530061337642, 1785.4732853808157, 1198.6284251881943, 1285.5115056278555, 1287.620152055146, 1294.7734146524322, 1321.4978308242805, 4854.514548545118, 5177.197534382177, 5228.686278823283, 5254.6847189168775, 5274.472552744694, 5443.42844001382, 5479.917801232985, 5750.6262092963825, 5820.476157145878, 5838.620489668158, 4808.942032752789, 4950.381202349707, 5019.820342219576, 5079.644424890296, 5229.267902493654, 2508.5108403861072, 2904.3880646241537, 3019.667836537979, 3044.2998110434282, 3058.8317642529873, 4949.925751113737, 5059.822906198266, 5161.793528258902, 5751.884962300731, 5876.353601103875, 5502.394736578973, 5725.2843394575675, 6137.18952908893, 6175.907361389583, 6236.970899801573, 5444.401115877171, 6278.937210627927, 6307.571584939831, 6314.526410519229, 6337.873242535124, 5177.26280453396, 5513.4562322549755, 5704.229928959538, 5717.599188198918, 5953.493953014043, 5061.342025536656, 5883.882322353615, 5947.1079338099635, 6020.307664618625, 6023.518118550418, 5045.772940217773, 6112.969435152796, 6241.751649669994, 6278.874422511615, 6293.311482945235, 4713.3636959011465, 4745.857624271277, 4747.5817411463695, 4764.190087685171, 4770.900803646385, 5583.692896890548, 5920.259967504062, 5974.970125149346, 6021.397860213943, 6042.761233946942, 4835.6131509479865, 5305.60207984395, 5780.219670344618, 5813.709314534341, 5871.240516957023, 5299.788008479618, 5591.980330621582, 5751.1085781703905, 5854.5609079320075, 5876.823695289148, 5153.484651534816, 5648.4633959773755, 5663.943360566342, 5674.943250567441, 5716.857078573069, 5587.904535799075, 5824.15549745293, 5881.7647294109465, 5892.970535147297, 5921.319048309328, 4905.754712264301, 5346.584099081196, 5712.400197979318, 5783.132530120347, 5814.307914474424], "value": 4508.204187629903, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.3514218210703, 662.9469642428606, 750.8160500677335, 825.640846231889, 839.3704721458906, 12522.686932826673, 12802.93598532006, 14780.96533242672, 15964.600884977881, 16116.677666446663, 16121.677566448663, 16369.345226190939, 16383.4265800697, 16550.337986480525, 16604.83395166048, 928.3919033033357, 950.6672664567416, 951.8381875081253, 952.1620973543272, 952.8951815300311, 898.9235914947237, 950.5769932380093, 951.3150531617224, 951.9095685909828, 952.1525842000625, 1002.0780881588919, 1013.878334599849, 1017.0, 1017.2828156149897, 1018.8828284747237, 1009.6314845081492, 1010.1867996263079, 1013.6553571785591, 1014.0417305646167, 1014.7767491151935, 742.4951033297356, 743.7917383132677, 745.5377665847155, 745.6160077560006, 746.0450623202255, 15654.31185691952, 15867.682646347008, 15985.0, 16047.277872495766, 16424.507264781958, 11752.647420577396, 13763.793543096861, 13970.231637259947, 14031.368588413547, 14061.296935153201, 11460.71348216293, 11666.358350290731, 11676.1826672133, 11774.352410617412, 12366.19619724715, 3615.191527915996, 3666.926661466756, 3668.5047518585184, 3736.8692095776787, 3794.5256842894637, 98197.5090124552, 110310.0, 110980.0, 112469.0, 114915.0, 10461.57503008342, 10677.893221067845, 10793.67618971432, 10799.406032668121, 11093.611723589796, 22239.110435582395, 22621.2082577109, 22679.092836286363, 22788.202412915467, 22802.087916483488, 7471.364933980563, 7501.887471687929, 7520.774376768596, 7606.885896711554, 7645.388368930472, 4177.26897792885, 4245.639120674778, 4248.936265956013, 4290.570942905684, 4299.634531064895, 3360.815155166489, 3456.343294773985, 3457.6542345765724, 3464.9307013860084, 3465.7227421806197, 530.7691154347871, 614.7387360371824, 626.7680958045461, 629.5561629051599, 639.5139693832627, 579.2383016333508, 626.7211091064523, 658.3219284137347, 700.8214140974836, 707.2598139728397, 9181.678641247525, 9407.284122771604, 10568.841467377995, 10775.946120269506, 10968.341899486057, 549.1296295371881, 558.6146357034577, 560.4133545310049, 615.9014557670752, 682.5938566552992, 4398.6803958812225, 4415.6909016368545, 4543.727376357429, 4656.883577910579, 4854.2233242681, 59424.0, 69060.0, 93535.53232233795, 95164.52417738005, 95821.52089239508, 61221.69389153026, 64467.74362897276, 69712.6514367425, 83066.0, 86114.13885861062, 72091.0, 72502.63748681222, 72997.17507062366, 73368.798936032, 73945.26054739491, 10383.117435017806, 10499.160067194452, 10610.89389106099, 10648.946755266174, 10759.677209683721, 47448.813779655786, 49133.0, 49825.0, 49880.25179622236, 50382.74808625934, 63102.511880516904, 64053.67973160104, 64502.0, 64701.67649161724, 66864.0, 12017.939910300393, 12126.27242365461, 12186.451609677762, 12272.44773985155, 12925.612231633066, 13928.58214253574, 14013.29933503341, 14024.859751402355, 14057.0, 14315.355808988412, 12240.449179787109, 12380.690482738004, 12382.257064575793, 12604.11771176009, 12652.114351995271, 12098.334591597546, 12508.749825003268, 12658.620241392773, 12669.8099528509, 13180.604581862559, 11936.940315298369, 12202.75594488123, 12310.07674424404, 12334.75330493403, 12797.8080328797, 8553.529555874238, 10245.692629221136, 10282.331648442827, 10666.679999600025, 10924.672259832218, 10297.588096476356, 10535.841962370723, 10536.473176341306, 10549.314294570828, 10621.946890265499], "value": 7399.781245616914, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1559.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 119.0, 119.0, 120.0, 119.0, 137.0, 182.0, 131.0, 118.0, 119.0], "value": 127.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 117.0, 133.0, 184.0, 141.0, 115.0, 111.0, 114.0, 137.0, 114.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 204.0, 220.0, 201.0, 218.0, 293.0, 210.0, 203.0, 195.0, 200.0], "value": 215.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 61.0, 62.0, 87.0, 59.0, 60.0, 61.0, 62.0, 77.0, 52.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 84.0, 74.0, 72.0, 76.0, 114.0, 101.0, 84.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 89.0, 105.0, 88.0, 94.0, 90.0, 98.0, 111.0, 138.0, 105.0], "value": 102.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 121.0, 111.0, 96.0, 98.0, 97.0, 98.0, 107.0, 98.0], "value": 101.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 91.0, 91.0, 91.0, 89.0, 91.0, 92.0, 90.0, 92.0], "value": 91.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 64.0, 68.0, 68.0, 68.0, 66.0, 67.0, 68.0, 66.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 63.0, 59.0, 71.0, 65.0, 65.0, 61.0, 60.0, 58.0, 59.0], "value": 61.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 80.0, 99.0, 107.0, 76.0, 103.0, 108.0, 107.0, 107.0, 84.0], "value": 98.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 164.0, 141.0, 145.0, 142.0, 145.0, 168.0, 153.0, 212.0, 161.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 178.0, 175.0, 177.0, 174.0, 176.0, 177.0, 175.0, 175.0, 172.0], "value": 175.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 73.0, 86.0, 93.0, 91.0, 87.0, 93.0, 77.0, 73.0], "value": 81.1, "unit": "score"}]}]} 07:54:19 INFO - Return code: 0 07:54:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:54:19 ERROR - # TBPL SUCCESS # 07:54:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:54:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:54:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:54:19 INFO - 2017-03-01 07:54:19.171 screenresolution[1927:19833] starting screenresolution argv=screenresolution get 07:54:19 INFO - 2017-03-01 07:54:19.174 screenresolution[1927:19833] Display 0: 1600x1200x32@60 07:54:19 INFO - 2017-03-01 07:54:19.180 screenresolution[1928:19836] starting screenresolution argv=screenresolution list 07:54:19 INFO - Available Modes on Display 0 07:54:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:54:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:54:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:54:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:54:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:54:19 INFO - Intel Iris: 07:54:19 INFO - Chipset Model: Intel Iris 07:54:19 INFO - Type: GPU 07:54:19 INFO - Bus: Built-In 07:54:19 INFO - VRAM (Dynamic, Max): 1536 MB 07:54:19 INFO - Vendor: Intel (0x8086) 07:54:19 INFO - Device ID: 0x0a2e 07:54:19 INFO - Revision ID: 0x0009 07:54:19 INFO - Displays: 07:54:19 INFO - Display: 07:54:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:54:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:54:19 INFO - Display Serial Number: 1600x1200 60 07:54:19 INFO - Main Display: Yes 07:54:19 INFO - Mirror: Off 07:54:19 INFO - Online: Yes 07:54:19 INFO - Rotation: Supported 07:54:19 INFO - Return code: 0 07:54:19 INFO - Running post-action listener: _resource_record_post_action 07:54:19 INFO - [mozharness: 2017-03-01 15:54:19.425451Z] Finished run-tests step (success) 07:54:19 INFO - Running post-run listener: _resource_record_post_run 07:54:19 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 1914368; Write bytes: 514146816; Read time: 17; Write time: 1599 07:54:19 INFO - TinderboxPrint: CPU usage
54.3% 07:54:19 INFO - TinderboxPrint: I/O read bytes / time
1,914,368 / 17 07:54:19 INFO - TinderboxPrint: I/O write bytes / time
514,146,816 / 1,599 07:54:19 INFO - TinderboxPrint: CPU idle
2,195.3 (45.7%) 07:54:19 INFO - TinderboxPrint: CPU system
957.8 (19.9%) 07:54:19 INFO - TinderboxPrint: CPU user
1,652.7 (34.4%) 07:54:19 INFO - TinderboxPrint: Swap in / out
212,783,104 / 0 07:54:19 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203860480; Write bytes: 202810880; Read time: 15644; Write time: 440 07:54:19 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 1910272; Write bytes: 300665856; Read time: 16; Write time: 1122 07:54:19 INFO - Running post-run listener: _upload_blobber_files 07:54:19 INFO - Blob upload gear active. 07:54:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:54:19 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 07:54:19 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'] 07:54:19 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 07:54:20 INFO - (blobuploader) - INFO - Open directory for files ... 07:54:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:54:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:54:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:54:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:54:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:54:21 INFO - (blobuploader) - INFO - Done attempting. 07:54:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:54:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:54:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:54:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:54:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:54:22 INFO - (blobuploader) - INFO - Done attempting. 07:54:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:54:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:54:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:54:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:54:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:54:23 INFO - (blobuploader) - INFO - Done attempting. 07:54:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:54:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:54:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:54:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:54:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:54:25 INFO - (blobuploader) - INFO - Done attempting. 07:54:25 INFO - (blobuploader) - INFO - Iteration through files over. 07:54:25 INFO - Return code: 0 07:54:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:54:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:54:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b8e26c792c8578c7563efdb50e689eae82ad7cc9a77669de5f3520a7c7d03c521f346a5ff503437ce550c1f78918eada589b2a1777590295edf90a087de4534", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e2a7faa5814ec17a7d3ab87555dfe4e77ab00ac15911c4a9df4ffb69847bb5822c40cbe73406098360d2d7379bd56a757edba3f37d7920f6f8c1a09167014de", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a79b1e7b3526efa8281f9d0e41b646fe7b1360ebeaa935b2c255fe6987486be8556b4b82d69c97d058f23bece687668259999d0bd513ce0ce829f646b69dbcf0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1dc4cdf2fd21928d55257e856263b6b56aa4243f64b8a8a98424bfda5274e5c5d7ee3498e348c84afa25d47953bb9afb6bb53b92ef3323440b9e360f9e6a1272"} 07:54:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:54:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:54:25 INFO - Contents: 07:54:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b8e26c792c8578c7563efdb50e689eae82ad7cc9a77669de5f3520a7c7d03c521f346a5ff503437ce550c1f78918eada589b2a1777590295edf90a087de4534", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e2a7faa5814ec17a7d3ab87555dfe4e77ab00ac15911c4a9df4ffb69847bb5822c40cbe73406098360d2d7379bd56a757edba3f37d7920f6f8c1a09167014de", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a79b1e7b3526efa8281f9d0e41b646fe7b1360ebeaa935b2c255fe6987486be8556b4b82d69c97d058f23bece687668259999d0bd513ce0ce829f646b69dbcf0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1dc4cdf2fd21928d55257e856263b6b56aa4243f64b8a8a98424bfda5274e5c5d7ee3498e348c84afa25d47953bb9afb6bb53b92ef3323440b9e360f9e6a1272"} 07:54:25 INFO - Running post-run listener: copy_logs_to_upload_dir 07:54:25 INFO - Copying logs to upload dir... 07:54:25 INFO - mkdir: /builds/slave/test/build/upload/logs 07:54:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.268119 ========= master_lag: 0.62 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-03-01 07:54:26.016792) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-01 07:54:26.020564) ========= 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.scRvxXCVV4/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.66ky427u0D/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b8e26c792c8578c7563efdb50e689eae82ad7cc9a77669de5f3520a7c7d03c521f346a5ff503437ce550c1f78918eada589b2a1777590295edf90a087de4534", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e2a7faa5814ec17a7d3ab87555dfe4e77ab00ac15911c4a9df4ffb69847bb5822c40cbe73406098360d2d7379bd56a757edba3f37d7920f6f8c1a09167014de", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a79b1e7b3526efa8281f9d0e41b646fe7b1360ebeaa935b2c255fe6987486be8556b4b82d69c97d058f23bece687668259999d0bd513ce0ce829f646b69dbcf0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1dc4cdf2fd21928d55257e856263b6b56aa4243f64b8a8a98424bfda5274e5c5d7ee3498e348c84afa25d47953bb9afb6bb53b92ef3323440b9e360f9e6a1272"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011270 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b8e26c792c8578c7563efdb50e689eae82ad7cc9a77669de5f3520a7c7d03c521f346a5ff503437ce550c1f78918eada589b2a1777590295edf90a087de4534", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e2a7faa5814ec17a7d3ab87555dfe4e77ab00ac15911c4a9df4ffb69847bb5822c40cbe73406098360d2d7379bd56a757edba3f37d7920f6f8c1a09167014de", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a79b1e7b3526efa8281f9d0e41b646fe7b1360ebeaa935b2c255fe6987486be8556b4b82d69c97d058f23bece687668259999d0bd513ce0ce829f646b69dbcf0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1dc4cdf2fd21928d55257e856263b6b56aa4243f64b8a8a98424bfda5274e5c5d7ee3498e348c84afa25d47953bb9afb6bb53b92ef3323440b9e360f9e6a1272"}' ========= master_lag: 0.09 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-01 07:54:26.121943) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2017-03-01 07:54:26.122273) ========= 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.scRvxXCVV4/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.66ky427u0D/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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.005421 ========= master_lag: 4.81 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2017-03-01 07:54:30.939196) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-01 07:54:30.939516) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-01 07:54:31.090218) ========= ========= Total master_lag: 5.59 =========