builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0291 starttime: 1488381843.66 results: success (0) buildid: 20170301072336 builduid: 7c30fda4e6d84bbdafbc45cd8658a9b4 revision: 34c6c2f302e7b48e3ad2cec575cbd34d423a9d32 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-01 07:24:03.657214) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-01 07:24:03.657716) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-01 07:24:03.658041) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-01 07:24:03.690523) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 07:24:03.690860) ========= 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.QRA5LIuqlW/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.rVEUYRDJs5/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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:24:03-- 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% 18.6M=0.001s 2017-03-01 07:24:03 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.086072 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 07:24:03.806647) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 07:24:03.806969) ========= 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.QRA5LIuqlW/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.rVEUYRDJs5/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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.032418 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 07:24:03.862644) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-01 07:24:03.863086) ========= 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.QRA5LIuqlW/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.rVEUYRDJs5/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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:24:03,972 truncating revision to first 12 chars 2017-03-01 07:24:03,972 Setting DEBUG logging. 2017-03-01 07:24:03,972 attempt 1/10 2017-03-01 07:24:03,972 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:24:05,047 unpacking tar archive at: mozilla-central-34c6c2f302e7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.341370 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-01 07:24:05.232088) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-01 07:24:05.232787) ========= script_repo_revision: 34c6c2f302e7b48e3ad2cec575cbd34d423a9d32 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-01 07:24:05.233674) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-01 07:24:05.234380) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-01 07:24:05.256529) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-01 07:24:05.257230) ========= /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.QRA5LIuqlW/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.rVEUYRDJs5/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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:24:05 INFO - MultiFileLogger online at 20170301 07:24:05 in /builds/slave/test 07:24:05 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:24:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:24:05 INFO - {'append_to_log': False, 07:24:05 INFO - 'base_work_dir': '/builds/slave/test', 07:24:05 INFO - 'blob_upload_branch': 'Firefox', 07:24:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:24:05 INFO - 'branch': 'Firefox', 07:24:05 INFO - 'buildbot_json_path': 'buildprops.json', 07:24:05 INFO - 'config_files': ('talos/mac_config.py',), 07:24:05 INFO - 'default_actions': ('clobber', 07:24:05 INFO - 'read-buildbot-config', 07:24:05 INFO - 'download-and-extract', 07:24:05 INFO - 'populate-webroot', 07:24:05 INFO - 'create-virtualenv', 07:24:05 INFO - 'install', 07:24:05 INFO - 'run-tests'), 07:24:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:24:05 INFO - 'download_minidump_stackwalk': True, 07:24:05 INFO - 'download_symbols': 'ondemand', 07:24:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:24:05 INFO - 'tooltool.py': '/tools/tooltool.py', 07:24:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:24:05 INFO - '/tools/misc-python/virtualenv.py')}, 07:24:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:24:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:24:05 INFO - 'gecko_profile': False, 07:24:05 INFO - 'gecko_profile_interval': 0, 07:24:05 INFO - 'installer_path': 'installer.exe', 07:24:05 INFO - 'log_level': 'info', 07:24:05 INFO - 'log_name': 'talos', 07:24:05 INFO - 'log_to_console': True, 07:24:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:24:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:24:05 INFO - 'opt_config_files': (), 07:24:05 INFO - 'pip_index': False, 07:24:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:24:05 INFO - 'cmd': ('bash', 07:24:05 INFO - '-c', 07:24:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:24:05 INFO - 'enabled': True, 07:24:05 INFO - 'halt_on_failure': False, 07:24:05 INFO - 'name': 'check_screen_resolution'},), 07:24:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:24:05 INFO - 'cmd': ('bash', 07:24:05 INFO - '-c', 07:24:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:24:05 INFO - 'enabled': True, 07:24:05 INFO - 'halt_on_failure': False, 07:24:05 INFO - 'name': 'check_screen_resolution'},), 07:24:05 INFO - 'run_cmd_checks_enabled': True, 07:24:05 INFO - 'suite': 'dromaeojs-e10s', 07:24:05 INFO - 'system_bits': '32', 07:24:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:24:05 INFO - 'title': 't-yosemite-r7-0291', 07:24:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:24:05 INFO - 'use_talos_json': True, 07:24:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:24:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:24:05 INFO - 'work_dir': 'build'} 07:24:05 INFO - [mozharness: 2017-03-01 15:24:05.475100Z] Running clobber step. 07:24:05 INFO - Running pre-action listener: _resource_record_pre_action 07:24:05 INFO - Running main action method: clobber 07:24:05 INFO - rmtree: /builds/slave/test/build 07:24:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:24:08 INFO - Running post-action listener: _resource_record_post_action 07:24:08 INFO - [mozharness: 2017-03-01 15:24:08.011132Z] Finished clobber step (success) 07:24:08 INFO - [mozharness: 2017-03-01 15:24:08.011419Z] Running read-buildbot-config step. 07:24:08 INFO - Running pre-action listener: _resource_record_pre_action 07:24:08 INFO - Running main action method: read_buildbot_config 07:24:08 INFO - Using buildbot properties: 07:24:08 INFO - { 07:24:08 INFO - "project": "", 07:24:08 INFO - "product": "firefox", 07:24:08 INFO - "script_repo_revision": "production", 07:24:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:24:08 INFO - "repository": "", 07:24:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:24:08 INFO - "buildid": "20170301072336", 07:24:08 INFO - "stage_platform": "macosx64", 07:24:08 INFO - "basedir": "/builds/slave/test", 07:24:08 INFO - "buildnumber": 0, 07:24:08 INFO - "platform": "yosemite_r7", 07:24:08 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 07:24:08 INFO - "slavebuilddir": "test", 07:24:08 INFO - "branch": "mozilla-central", 07:24:08 INFO - "revision": "34c6c2f302e7b48e3ad2cec575cbd34d423a9d32", 07:24:08 INFO - "slavename": "t-yosemite-r7-0291", 07:24:08 INFO - "builduid": "7c30fda4e6d84bbdafbc45cd8658a9b4", 07:24:08 INFO - "repo_path": "mozilla-central" 07:24:08 INFO - } 07:24: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:24: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:24:08 INFO - Running post-action listener: _resource_record_post_action 07:24:08 INFO - [mozharness: 2017-03-01 15:24:08.016948Z] Finished read-buildbot-config step (success) 07:24:08 INFO - [mozharness: 2017-03-01 15:24:08.017144Z] Running download-and-extract step. 07:24:08 INFO - Running pre-action listener: _resource_record_pre_action 07:24:08 INFO - Running main action method: download_and_extract 07:24:08 INFO - mkdir: /builds/slave/test/build/tests 07:24: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:24: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:24: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:24: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:24:08 INFO - Downloaded 1199 bytes. 07:24:08 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 07:24:08 INFO - Using the following test package requirements: 07:24:08 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 07:24:08 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:24:08 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 07:24:08 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:24:08 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 07:24:08 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:24:08 INFO - u'jsshell-mac.zip'], 07:24:08 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:24:08 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 07:24:08 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 07:24:08 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:24:08 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 07:24:08 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:24:08 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 07:24:08 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:24:08 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 07:24:08 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:24:08 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 07:24:08 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:24: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:24: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:24: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:24:09 INFO - Expected file size: 51762826 07:24:09 INFO - Obtained file size: 51762826 07:24: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:24: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:24: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:24: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:24:26 INFO - Expected file size: 51762826 07:24:26 INFO - Obtained file size: 51762826 07:24: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:24: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:24: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:24:41 INFO - Expected file size: 14404421 07:24:41 INFO - Obtained file size: 14404421 07:24:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:41 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.dmg 07:24:41 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:24:41 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:24:47 INFO - Downloaded 74973774 bytes. 07:24:47 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:24:47 INFO - mkdir: /builds/slave/test/properties 07:24:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:24:47 INFO - Writing to file /builds/slave/test/properties/build_url 07:24:47 INFO - Contents: 07:24:47 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.dmg 07:24:48 INFO - Running post-action listener: _resource_record_post_action 07:24:48 INFO - Running post-action listener: set_extra_try_arguments 07:24:48 INFO - [mozharness: 2017-03-01 15:24:48.394115Z] Finished download-and-extract step (success) 07:24:48 INFO - [mozharness: 2017-03-01 15:24:48.394232Z] Running populate-webroot step. 07:24:48 INFO - Running pre-action listener: _resource_record_pre_action 07:24:48 INFO - Running main action method: populate_webroot 07:24:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:24:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:24:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:24:48 INFO - '--tppagecycles', 07:24:48 INFO - '7'], 07:24:48 INFO - 'tests': ['tsvgm']}}, 07:24:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:24:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:24:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:24:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:24:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:24:48 INFO - 'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:24:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:24:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:24:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:24:48 INFO - 'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['damp', 'tps']}, 07:24:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:24:48 INFO - 'tests': ['damp', 'tps']}, 07:24:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['dromaeo_dom']}, 07:24:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:24:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['basic_compositor_video']}, 07:24:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:24:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['a11yr', 07:24:48 INFO - 'ts_paint', 07:24:48 INFO - 'tpaint', 07:24:48 INFO - 'sessionrestore', 07:24:48 INFO - 'sessionrestore_no_auto_restore', 07:24:48 INFO - 'tabpaint']}, 07:24:48 INFO - 'other-e10s': {'tests': ['a11yr', 07:24:48 INFO - 'ts_paint', 07:24:48 INFO - 'tpaint', 07:24:48 INFO - 'sessionrestore', 07:24:48 INFO - 'sessionrestore_no_auto_restore', 07:24:48 INFO - 'tabpaint']}, 07:24:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:24:48 INFO - 'ts_paint', 07:24:48 INFO - 'tpaint', 07:24:48 INFO - 'sessionrestore', 07:24:48 INFO - 'sessionrestore_no_auto_restore', 07:24:48 INFO - 'tabpaint']}, 07:24:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:24:48 INFO - 'ts_paint', 07:24:48 INFO - 'tpaint', 07:24:48 INFO - 'sessionrestore', 07:24:48 INFO - 'sessionrestore_no_auto_restore', 07:24:48 INFO - 'tabpaint']}, 07:24:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['a11yr', 07:24:48 INFO - 'ts_paint', 07:24:48 INFO - 'tpaint', 07:24:48 INFO - 'sessionrestore', 07:24:48 INFO - 'sessionrestore_no_auto_restore', 07:24:48 INFO - 'tabpaint']}, 07:24:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['a11yr', 07:24:48 INFO - 'ts_paint', 07:24:48 INFO - 'tpaint', 07:24:48 INFO - 'sessionrestore', 07:24:48 INFO - 'sessionrestore_no_auto_restore', 07:24:48 INFO - 'tabpaint']}, 07:24:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['tsvgx', 07:24:48 INFO - 'tsvgr_opacity', 07:24:48 INFO - 'tart', 07:24:48 INFO - 'tscrollx', 07:24:48 INFO - 'cart', 07:24:48 INFO - 'tsvg_static']}, 07:24:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:24:48 INFO - 'tsvgr_opacity', 07:24:48 INFO - 'tart', 07:24:48 INFO - 'tscrollx', 07:24:48 INFO - 'cart', 07:24:48 INFO - 'tsvg_static']}, 07:24:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:24:48 INFO - 'talos_options': ['--disable-e10s'], 07:24:48 INFO - 'tests': ['tp5o']}, 07:24:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:24:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:24:48 INFO - 'talos_options': ['--disable-e10s', 07:24:48 INFO - '--xperf_path', 07:24:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:24:48 INFO - 'tests': ['tp5n']}, 07:24:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:24:48 INFO - 'talos_options': ['--xperf_path', 07:24:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:24:48 INFO - 'tests': ['tp5n']}}, 07:24:48 INFO - 'talos.zip': {'path': '', 07:24:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:24:48 INFO - Running post-action listener: _resource_record_post_action 07:24:48 INFO - [mozharness: 2017-03-01 15:24:48.412630Z] Finished populate-webroot step (success) 07:24:48 INFO - [mozharness: 2017-03-01 15:24:48.412746Z] Running create-virtualenv step. 07:24:48 INFO - Running pre-action listener: _resource_record_pre_action 07:24:48 INFO - Running main action method: create_virtualenv 07:24:48 INFO - Creating virtualenv /builds/slave/test/build/venv 07:24:48 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:24:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:24:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:24:48 INFO - Using real prefix '/tools/python27' 07:24:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:24:49 INFO - Installing distribute.............................................................................................................................................................................................done. 07:24:52 INFO - Installing pip.................done. 07:24:52 INFO - Return code: 0 07:24:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:24:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:24:52 INFO - Reading from file tmpfile_stdout 07:24:52 INFO - Output received: 07:24:52 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:24:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:24:52 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:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:52 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:24:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:52 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 0x10c2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0b3d661a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c091d30>, '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 0x10bfb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c086be8>, '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.QRA5LIuqlW/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.rVEUYRDJs5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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:24:52 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:24:52 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:24:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QRA5LIuqlW/Render', 07:24:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:52 INFO - 'HOME': '/Users/cltbld', 07:24:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:52 INFO - 'LOGNAME': 'cltbld', 07:24:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:52 INFO - 'MOZ_NO_REMOTE': '1', 07:24:52 INFO - 'NO_EM_RESTART': '1', 07:24:52 INFO - 'PAGER': '/bin/cat', 07:24:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:52 INFO - 'PWD': '/builds/slave/test', 07:24:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:52 INFO - 'SHELL': '/bin/bash', 07:24:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rVEUYRDJs5/Listeners', 07:24:52 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 07:24:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:52 INFO - 'USER': 'cltbld', 07:24:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:52 INFO - 'XPC_FLAGS': '0x0', 07:24:52 INFO - 'XPC_SERVICE_NAME': '0', 07:24:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:52 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:24:52 INFO - Cleaning up... 07:24:52 INFO - Return code: 0 07:24:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:24:52 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:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:52 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:24:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:52 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 0x10c2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0b3d661a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c091d30>, '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 0x10bfb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c086be8>, '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.QRA5LIuqlW/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.rVEUYRDJs5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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:24:52 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:24:52 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:24:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QRA5LIuqlW/Render', 07:24:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:52 INFO - 'HOME': '/Users/cltbld', 07:24:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:52 INFO - 'LOGNAME': 'cltbld', 07:24:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:52 INFO - 'MOZ_NO_REMOTE': '1', 07:24:52 INFO - 'NO_EM_RESTART': '1', 07:24:52 INFO - 'PAGER': '/bin/cat', 07:24:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:52 INFO - 'PWD': '/builds/slave/test', 07:24:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:52 INFO - 'SHELL': '/bin/bash', 07:24:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rVEUYRDJs5/Listeners', 07:24:52 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 07:24:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:52 INFO - 'USER': 'cltbld', 07:24:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:52 INFO - 'XPC_FLAGS': '0x0', 07:24:52 INFO - 'XPC_SERVICE_NAME': '0', 07:24:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:52 INFO - Downloading/unpacking psutil>=3.1.1 07:24:52 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:24:52 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:24:52 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:24:52 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:24:52 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:24:52 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:24:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:24:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:24:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:24:56 INFO - Installing collected packages: psutil 07:24:56 INFO - Running setup.py install for psutil 07:24:56 INFO - building 'psutil._psutil_osx' extension 07:24:56 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:24:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:24:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:24:57 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:24:57 INFO - building 'psutil._psutil_posix' extension 07:24:57 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:24:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:24:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:24:57 INFO - ^ 07:24:57 INFO - 1 warning generated. 07:24:57 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:24:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:24:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:24:57 INFO - Successfully installed psutil 07:24:57 INFO - Cleaning up... 07:24:57 INFO - Return code: 0 07:24:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:24:57 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:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:57 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:24:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0b3d661a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c091d30>, '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 0x10bfb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c086be8>, '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.QRA5LIuqlW/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.rVEUYRDJs5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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:24:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:24:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QRA5LIuqlW/Render', 07:24:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:58 INFO - 'HOME': '/Users/cltbld', 07:24:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:58 INFO - 'LOGNAME': 'cltbld', 07:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:58 INFO - 'MOZ_NO_REMOTE': '1', 07:24:58 INFO - 'NO_EM_RESTART': '1', 07:24:58 INFO - 'PAGER': '/bin/cat', 07:24:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:58 INFO - 'PWD': '/builds/slave/test', 07:24:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:58 INFO - 'SHELL': '/bin/bash', 07:24:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rVEUYRDJs5/Listeners', 07:24:58 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 07:24:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:58 INFO - 'USER': 'cltbld', 07:24:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:58 INFO - 'XPC_FLAGS': '0x0', 07:24:58 INFO - 'XPC_SERVICE_NAME': '0', 07:24:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:25:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:25:01 INFO - Installing collected packages: mozsystemmonitor 07:25:01 INFO - Running setup.py install for mozsystemmonitor 07:25:01 INFO - Successfully installed mozsystemmonitor 07:25:01 INFO - Cleaning up... 07:25:01 INFO - Return code: 0 07:25:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:25: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:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25: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:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25: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', '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 0x10c2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0b3d661a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c091d30>, '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 0x10bfb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c086be8>, '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.QRA5LIuqlW/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.rVEUYRDJs5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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:25: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:25: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 jsonschema==2.5.1 07:25:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QRA5LIuqlW/Render', 07:25:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:01 INFO - 'HOME': '/Users/cltbld', 07:25:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:01 INFO - 'LOGNAME': 'cltbld', 07:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:01 INFO - 'MOZ_NO_REMOTE': '1', 07:25:01 INFO - 'NO_EM_RESTART': '1', 07:25:01 INFO - 'PAGER': '/bin/cat', 07:25:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:01 INFO - 'PWD': '/builds/slave/test', 07:25:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:01 INFO - 'SHELL': '/bin/bash', 07:25:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rVEUYRDJs5/Listeners', 07:25:01 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 07:25:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:01 INFO - 'USER': 'cltbld', 07:25:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:01 INFO - 'XPC_FLAGS': '0x0', 07:25:01 INFO - 'XPC_SERVICE_NAME': '0', 07:25:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:02 INFO - Downloading/unpacking jsonschema==2.5.1 07:25:02 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:25:02 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:25:02 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:25:02 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:25:02 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:25:02 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:25:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:25:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:25: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:25: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:25: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:25: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:25: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:25: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:25:05 INFO - Downloading functools32-3.2.3-2.tar.gz 07:25:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:25:05 INFO - warning: no files found matching '*.txt' 07:25:05 INFO - no previously-included directories found matching 'build' 07:25:05 INFO - no previously-included directories found matching 'dist' 07:25:05 INFO - no previously-included directories found matching '.git*' 07:25:05 INFO - Installing collected packages: jsonschema, functools32 07:25:05 INFO - Running setup.py install for functools32 07:25:05 INFO - warning: no files found matching '*.txt' 07:25:05 INFO - no previously-included directories found matching 'build' 07:25:05 INFO - no previously-included directories found matching 'dist' 07:25:05 INFO - no previously-included directories found matching '.git*' 07:25:05 INFO - Successfully installed jsonschema functools32 07:25:05 INFO - Cleaning up... 07:25:05 INFO - Return code: 0 07:25:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:05 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 0x10c2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0b3d661a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c091d30>, '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 0x10bfb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c086be8>, '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.QRA5LIuqlW/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.rVEUYRDJs5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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:25:05 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:25:05 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:25:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QRA5LIuqlW/Render', 07:25:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:05 INFO - 'HOME': '/Users/cltbld', 07:25:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:05 INFO - 'LOGNAME': 'cltbld', 07:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:05 INFO - 'MOZ_NO_REMOTE': '1', 07:25:05 INFO - 'NO_EM_RESTART': '1', 07:25:05 INFO - 'PAGER': '/bin/cat', 07:25:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:05 INFO - 'PWD': '/builds/slave/test', 07:25:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:05 INFO - 'SHELL': '/bin/bash', 07:25:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rVEUYRDJs5/Listeners', 07:25:05 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 07:25:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:05 INFO - 'USER': 'cltbld', 07:25:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:05 INFO - 'XPC_FLAGS': '0x0', 07:25:05 INFO - 'XPC_SERVICE_NAME': '0', 07:25:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:25:05 INFO - Cleaning up... 07:25:05 INFO - Return code: 0 07:25:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:05 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 0x10c2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0b3d661a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c091d30>, '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 0x10bfb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c086be8>, '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.QRA5LIuqlW/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.rVEUYRDJs5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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:25:05 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:25:05 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:25:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QRA5LIuqlW/Render', 07:25:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:05 INFO - 'HOME': '/Users/cltbld', 07:25:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:05 INFO - 'LOGNAME': 'cltbld', 07:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:05 INFO - 'MOZ_NO_REMOTE': '1', 07:25:05 INFO - 'NO_EM_RESTART': '1', 07:25:05 INFO - 'PAGER': '/bin/cat', 07:25:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:05 INFO - 'PWD': '/builds/slave/test', 07:25:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:05 INFO - 'SHELL': '/bin/bash', 07:25:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rVEUYRDJs5/Listeners', 07:25:05 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 07:25:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:05 INFO - 'USER': 'cltbld', 07:25:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:05 INFO - 'XPC_FLAGS': '0x0', 07:25:05 INFO - 'XPC_SERVICE_NAME': '0', 07:25:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:06 INFO - Downloading/unpacking blobuploader==1.2.4 07:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:08 INFO - Downloading blobuploader-1.2.4.tar.gz 07:25:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:25:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:25:08 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:25:08 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:25:08 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:25:08 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:25:08 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:25:08 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:25:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:25:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:25:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:25:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:25:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:25:10 INFO - Downloading docopt-0.6.1.tar.gz 07:25:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:25:10 INFO - Installing collected packages: blobuploader, requests, docopt 07:25:10 INFO - Running setup.py install for blobuploader 07:25:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:25:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:25:10 INFO - Running setup.py install for requests 07:25:10 INFO - Running setup.py install for docopt 07:25:10 INFO - Successfully installed blobuploader requests docopt 07:25:10 INFO - Cleaning up... 07:25:11 INFO - Return code: 0 07:25:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25: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:25:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25: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:25:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:11 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 0x10c2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0b3d661a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c091d30>, '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 0x10bfb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c086be8>, '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.QRA5LIuqlW/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.rVEUYRDJs5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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:25:11 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:25:11 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:25:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QRA5LIuqlW/Render', 07:25:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:11 INFO - 'HOME': '/Users/cltbld', 07:25:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:11 INFO - 'LOGNAME': 'cltbld', 07:25:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:11 INFO - 'MOZ_NO_REMOTE': '1', 07:25:11 INFO - 'NO_EM_RESTART': '1', 07:25:11 INFO - 'PAGER': '/bin/cat', 07:25:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:11 INFO - 'PWD': '/builds/slave/test', 07:25:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:11 INFO - 'SHELL': '/bin/bash', 07:25:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rVEUYRDJs5/Listeners', 07:25:11 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 07:25:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:11 INFO - 'USER': 'cltbld', 07:25:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:11 INFO - 'XPC_FLAGS': '0x0', 07:25:11 INFO - 'XPC_SERVICE_NAME': '0', 07:25:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:25:11 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-UANTUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:25:11 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-VeXPwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:25:11 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-eA74Zj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:25:11 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-5FcoK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:25:11 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-QHDVK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:25:11 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-Rk2jkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:25:11 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-xLp5vT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:25:12 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-fDPUun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:25:12 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-P5QwZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:25:12 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-8Dhxx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:25:12 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-16Y5RJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:25:12 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-co5eWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:25:12 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-slIDAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:25:12 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-5YHTJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:12 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-a3GD4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:25:12 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-63BDxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:25:12 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-qSBWTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:25:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:25:13 INFO - Running setup.py install for manifestparser 07:25:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:25:13 INFO - Running setup.py install for mozcrash 07:25:13 INFO - Running setup.py install for mozdebug 07:25:13 INFO - Running setup.py install for mozdevice 07:25:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:25:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:25:13 INFO - Running setup.py install for mozfile 07:25:13 INFO - Running setup.py install for mozhttpd 07:25:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:25:13 INFO - Running setup.py install for mozinfo 07:25:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:25:14 INFO - Running setup.py install for mozInstall 07:25:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:25:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:25:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:25:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:25:14 INFO - Running setup.py install for mozleak 07:25:14 INFO - Running setup.py install for mozlog 07:25:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:25:14 INFO - Running setup.py install for moznetwork 07:25:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:25:14 INFO - Running setup.py install for mozprocess 07:25:14 INFO - Running setup.py install for mozprofile 07:25:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:25:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:25:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:25:15 INFO - Running setup.py install for mozrunner 07:25:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:25:15 INFO - Running setup.py install for mozscreenshot 07:25:15 INFO - Running setup.py install for moztest 07:25:15 INFO - Running setup.py install for mozversion 07:25:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:25:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:25:15 INFO - Cleaning up... 07:25:15 INFO - Return code: 0 07:25:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:15 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 0x10c2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0b3d661a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c091d30>, '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 0x10bfb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c086be8>, '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.QRA5LIuqlW/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.rVEUYRDJs5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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:25:15 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:25:15 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:25:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QRA5LIuqlW/Render', 07:25:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:15 INFO - 'HOME': '/Users/cltbld', 07:25:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:15 INFO - 'LOGNAME': 'cltbld', 07:25:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:15 INFO - 'MOZ_NO_REMOTE': '1', 07:25:15 INFO - 'NO_EM_RESTART': '1', 07:25:15 INFO - 'PAGER': '/bin/cat', 07:25:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:15 INFO - 'PWD': '/builds/slave/test', 07:25:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:15 INFO - 'SHELL': '/bin/bash', 07:25:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rVEUYRDJs5/Listeners', 07:25:15 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 07:25:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:15 INFO - 'USER': 'cltbld', 07:25:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:15 INFO - 'XPC_FLAGS': '0x0', 07:25:15 INFO - 'XPC_SERVICE_NAME': '0', 07:25:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:25:15 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-txDL8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:25:16 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:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:25:16 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-SNFuFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:25:16 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:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:25:16 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-C3tmRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:25:16 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:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:25:16 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-zSGLLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:25:16 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:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:25:16 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-zxBr_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:25:16 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:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:25:16 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-aeQz6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:25:16 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:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:25:16 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-nXwVu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:25:16 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:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:25:16 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-tKg8Mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:25:16 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:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:25:16 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-0T4OHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:25:16 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:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:25:16 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-Q9mqiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:25:16 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:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:25:16 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-3XA6U5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:25:17 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:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:25:17 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-SSevBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:25:17 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:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:25:17 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-M23Bda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:25:17 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:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:25:17 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-P7T5I9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:25:17 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:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:17 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-zm5FxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:25:17 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:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:25:17 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-nG9sGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:25:17 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:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:25:17 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-7_JQrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:25:17 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:25:17 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:25:17 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:25:17 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:25:17 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:25:17 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:25:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:25:17 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:25:17 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:25:17 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:25:17 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:25:17 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:25:17 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:25:20 INFO - Downloading blessings-1.6.tar.gz 07:25:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:25:20 INFO - Installing collected packages: blessings 07:25:20 INFO - Running setup.py install for blessings 07:25:20 INFO - Successfully installed blessings 07:25:20 INFO - Cleaning up... 07:25:20 INFO - Return code: 0 07:25:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:25:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:25:21 INFO - Reading from file tmpfile_stdout 07:25:21 INFO - Current package versions: 07:25:21 INFO - blessings == 1.6 07:25:21 INFO - blobuploader == 1.2.4 07:25:21 INFO - docopt == 0.6.1 07:25:21 INFO - functools32 == 3.2.3-2 07:25:21 INFO - jsonschema == 2.5.1 07:25:21 INFO - manifestparser == 1.1 07:25:21 INFO - mozInstall == 1.12 07:25:21 INFO - mozcrash == 1.0 07:25:21 INFO - mozdebug == 0.1 07:25:21 INFO - mozdevice == 0.49 07:25:21 INFO - mozfile == 1.2 07:25:21 INFO - mozhttpd == 0.7 07:25:21 INFO - mozinfo == 0.9 07:25:21 INFO - mozleak == 0.1 07:25:21 INFO - mozlog == 3.4 07:25:21 INFO - moznetwork == 0.27 07:25:21 INFO - mozprocess == 0.25 07:25:21 INFO - mozprofile == 0.28 07:25:21 INFO - mozrunner == 6.13 07:25:21 INFO - mozscreenshot == 0.1 07:25:21 INFO - mozsystemmonitor == 0.3 07:25:21 INFO - moztest == 0.8 07:25:21 INFO - mozversion == 1.4 07:25:21 INFO - psutil == 3.1.1 07:25:21 INFO - requests == 1.2.3 07:25:21 INFO - wsgiref == 0.1.2 07:25:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:21 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 0x10c2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0b3d661a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c091d30>, '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 0x10bfb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c086be8>, '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.QRA5LIuqlW/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.rVEUYRDJs5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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:25:21 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:25:21 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:25:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QRA5LIuqlW/Render', 07:25:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:21 INFO - 'HOME': '/Users/cltbld', 07:25:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:21 INFO - 'LOGNAME': 'cltbld', 07:25:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:21 INFO - 'MOZ_NO_REMOTE': '1', 07:25:21 INFO - 'NO_EM_RESTART': '1', 07:25:21 INFO - 'PAGER': '/bin/cat', 07:25:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:21 INFO - 'PWD': '/builds/slave/test', 07:25:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:21 INFO - 'SHELL': '/bin/bash', 07:25:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rVEUYRDJs5/Listeners', 07:25:21 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 07:25:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:21 INFO - 'USER': 'cltbld', 07:25:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:21 INFO - 'XPC_FLAGS': '0x0', 07:25:21 INFO - 'XPC_SERVICE_NAME': '0', 07:25:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:21 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:25:21 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:25:21 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:25:21 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:25:21 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:25:21 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:25:21 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:25:21 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:25:21 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:25:21 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:25:21 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:25:21 INFO - Cleaning up... 07:25:21 INFO - Return code: 0 07:25:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:25:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:25:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:25:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:25:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0b3d661a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c091d30>, '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 0x10bfb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c086be8>, '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.QRA5LIuqlW/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.rVEUYRDJs5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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:25:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:25:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QRA5LIuqlW/Render', 07:25:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:21 INFO - 'HOME': '/Users/cltbld', 07:25:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:21 INFO - 'LOGNAME': 'cltbld', 07:25:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:21 INFO - 'MOZ_NO_REMOTE': '1', 07:25:21 INFO - 'NO_EM_RESTART': '1', 07:25:21 INFO - 'PAGER': '/bin/cat', 07:25:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:21 INFO - 'PWD': '/builds/slave/test', 07:25:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:21 INFO - 'SHELL': '/bin/bash', 07:25:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rVEUYRDJs5/Listeners', 07:25:21 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 07:25:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:21 INFO - 'USER': 'cltbld', 07:25:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:21 INFO - 'XPC_FLAGS': '0x0', 07:25:21 INFO - 'XPC_SERVICE_NAME': '0', 07:25:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:25:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:25:21 INFO - Cleaning up... 07:25:21 INFO - Return code: 0 07:25:21 INFO - Running post-action listener: _resource_record_post_action 07:25:21 INFO - Running post-action listener: _start_resource_monitoring 07:25:21 INFO - Starting resource monitoring. 07:25:21 INFO - [mozharness: 2017-03-01 15:25:21.805668Z] Finished create-virtualenv step (success) 07:25:21 INFO - [mozharness: 2017-03-01 15:25:21.806682Z] Running install step. 07:25:21 INFO - Running pre-action listener: _resource_record_pre_action 07:25:21 INFO - Running main action method: install 07:25:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:25:22 INFO - Reading from file tmpfile_stdout 07:25:22 INFO - Detecting whether we're running mozinstall >=1.0... 07:25:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:25:22 INFO - Reading from file tmpfile_stdout 07:25:22 INFO - Output received: 07:25:22 INFO - Usage: mozinstall [options] installer 07:25:22 INFO - Options: 07:25:22 INFO - -h, --help show this help message and exit 07:25:22 INFO - -d DEST, --destination=DEST 07:25:22 INFO - Directory to install application into. [default: 07:25:22 INFO - "/builds/slave/test"] 07:25:22 INFO - --app=APP Application being installed. [default: firefox] 07:25:22 INFO - mkdir: /builds/slave/test/build/application 07:25:22 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:25:22 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:25:42 INFO - Reading from file tmpfile_stdout 07:25:42 INFO - Output received: 07:25:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:25:42 INFO - Running post-action listener: _resource_record_post_action 07:25:42 INFO - [mozharness: 2017-03-01 15:25:42.572365Z] Finished install step (success) 07:25:42 INFO - [mozharness: 2017-03-01 15:25:42.572525Z] Running run-tests step. 07:25:42 INFO - Running pre-action listener: _resource_record_pre_action 07:25:42 INFO - Running main action method: run_tests 07:25:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:25:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:25:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:25:42 INFO - 2017-03-01 07:25:42.585 screenresolution[1630:7420] starting screenresolution argv=screenresolution get 07:25:42 INFO - 2017-03-01 07:25:42.588 screenresolution[1630:7420] Display 0: 1600x1200x32@60 07:25:42 INFO - 2017-03-01 07:25:42.593 screenresolution[1631:7424] starting screenresolution argv=screenresolution list 07:25:42 INFO - Available Modes on Display 0 07:25:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:25:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:25:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:25:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:25:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:25:42 INFO - Intel Iris: 07:25:42 INFO - Chipset Model: Intel Iris 07:25:42 INFO - Type: GPU 07:25:42 INFO - Bus: Built-In 07:25:42 INFO - VRAM (Dynamic, Max): 1536 MB 07:25:42 INFO - Vendor: Intel (0x8086) 07:25:42 INFO - Device ID: 0x0a2e 07:25:42 INFO - Revision ID: 0x0009 07:25:42 INFO - Displays: 07:25:42 INFO - Display: 07:25:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:25:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:25:42 INFO - Display Serial Number: 1600x1200 60 07:25:42 INFO - Main Display: Yes 07:25:42 INFO - Mirror: Off 07:25:42 INFO - Online: Yes 07:25:42 INFO - Rotation: Supported 07:25:42 INFO - Return code: 0 07:25:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:25:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:25:42 INFO - Python 2.7.3 07:25:42 INFO - Return code: 0 07:25:42 INFO - grabbing minidump binary from tooltool 07:25: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:25:42 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 0x10c091d30>, '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 0x10bfb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c086be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:25:42 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:25:42 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:25:42 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:25:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:25:42 INFO - Return code: 0 07:25:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:25:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:25:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:25:42 INFO - ENV: RUST_BACKTRACE is now 1 07:25:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:25:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:25:42 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-0291', '--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:25:42 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-0291 --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:25:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QRA5LIuqlW/Render', 07:25:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:42 INFO - 'HOME': '/Users/cltbld', 07:25:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:42 INFO - 'LOGNAME': 'cltbld', 07:25:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:25:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:25:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:42 INFO - 'MOZ_NO_REMOTE': '1', 07:25:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:25:42 INFO - 'NO_EM_RESTART': '1', 07:25:42 INFO - 'PAGER': '/bin/cat', 07:25:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:42 INFO - 'PWD': '/builds/slave/test', 07:25:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:25:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:42 INFO - 'RUST_BACKTRACE': '1', 07:25:42 INFO - 'SHELL': '/bin/bash', 07:25:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rVEUYRDJs5/Listeners', 07:25:42 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 07:25:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:42 INFO - 'USER': 'cltbld', 07:25:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:42 INFO - 'XPC_FLAGS': '0x0', 07:25:42 INFO - 'XPC_SERVICE_NAME': '0', 07:25:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:42 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-0291', '--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:25:43 INFO - mozversion application_buildid: 20170301024203 07:25:43 INFO - mozversion application_changeset: 34c6c2f302e7b48e3ad2cec575cbd34d423a9d32 07:25:43 INFO - mozversion application_display_name: Nightly 07:25:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:25:43 INFO - mozversion application_name: Firefox 07:25:43 INFO - mozversion application_remotingname: firefox 07:25:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:25:43 INFO - mozversion application_vendor: Mozilla 07:25:43 INFO - mozversion application_version: 54.0a1 07:25:43 INFO - mozversion platform_buildid: 20170301024203 07:25:43 INFO - mozversion platform_changeset: 34c6c2f302e7b48e3ad2cec575cbd34d423a9d32 07:25:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:25:43 INFO - mozversion platform_version: 54.0a1 07:25:43 INFO - using testdate: 1488381943 07:25:43 INFO - actual date: 1488381943 07:25:43 INFO - starting webserver on 'localhost:49237' 07:25:43 INFO - SUITE-START | Running 2 tests 07:25:43 INFO - TEST-START | dromaeo_css 07:25:43 INFO - operating with platform_type : mac_ 07:25:43 INFO - Initialising browser for dromaeo_css test... 07:25:43 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpZp7s1j/profile http://localhost:49237/getInfo.html) 07:25:43 INFO - PROCESS | 1641 | 1488381943763 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:25:43 INFO - PROCESS | 1641 | 1488381943764 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 07:25:43 INFO - PROCESS | 1641 | 1488381943805 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 07:25:46 INFO - PROCESS | 1641 | 2017-03-01 07:25:46.246 plugin-container[1643:7608] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 07:25:46 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:25:46 INFO - PROCESS | 1641 | 2017-03-01 07:25:46.250 plugin-container[1643:7608] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 07:25:46 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:25:46 INFO - PROCESS | 1641 | 2017-03-01 07:25:46.250 plugin-container[1643:7608] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:25:46 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 07:25:46 INFO - PROCESS | 1641 | colorDepth:24 07:25:46 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 07:25:46 INFO - PROCESS | 1641 | __metrics 07:25:46 ERROR - PROCESS | 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 07:25:46 INFO - PROCESS | 1641 | 1488381946412 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 07:25:47 INFO - TEST-INFO | 1641: exit 0 07:25:47 INFO - Browser initialized. 07:25:47 INFO - Running cycle 1/1 for dromaeo_css test... 07:25:47 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpZp7s1j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:25:47 INFO - PROCESS | 1644 | 1488381947494 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:25:47 INFO - PROCESS | 1644 | 1488381947495 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 07:25:49 INFO - PROCESS | 1644 | 2017-03-01 07:25:49.908 plugin-container[1645:7743] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 07:25:49 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:25:49 INFO - PROCESS | 1644 | 2017-03-01 07:25:49.908 plugin-container[1645:7743] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 07:25:49 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:25:49 INFO - PROCESS | 1644 | 2017-03-01 07:25:49.909 plugin-container[1645:7743] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:25:59 INFO - PROCESS | 1644 | RSS: Main: 226451456 07:25:59 INFO - PROCESS | 1644 | 07:29:03 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 07:29:05 INFO - PROCESS | 1644 | RSS: Main: 254607360 07:29:05 INFO - PROCESS | 1644 | 07:30:47 INFO - PROCESS | 1644 | ************************* 07:30:47 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 07:30:47 INFO - PROCESS | 1644 | 07:30:47 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:30:47 INFO - PROCESS | 1644 | Full stack: 07:30:47 INFO - PROCESS | 1644 | ************************* 07:32:07 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 07:32:07 INFO - PROCESS | 1644 | RSS: Main: 257347584 07:32:07 INFO - PROCESS | 1644 | 07:35:10 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 07:35:10 INFO - PROCESS | 1644 | RSS: Main: 268615680 07:35:10 INFO - PROCESS | 1644 | 07:38:12 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 07:38:13 INFO - PROCESS | 1644 | RSS: Main: 262381568 07:38:13 INFO - PROCESS | 1644 | 07:41:16 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 07:41:16 INFO - PROCESS | 1644 | RSS: Main: 272576512 07:41:16 INFO - PROCESS | 1644 | 07:44:20 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 07:44:20 INFO - PROCESS | 1644 | RSS: Main: 266526720 07:44:20 INFO - PROCESS | 1644 | 07:44:20 INFO - PROCESS | 1644 | __start_tp_report 07:44:20 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 07:44:20 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 07:44:20 INFO - PROCESS | 1644 | |i|pagename|runs| 07:44:20 INFO - PROCESS | 1644 | |0;dojo.html;3442.913927151821;3472.7221822254205;3478.617352091268;3486.3375958567863;3490;5725.541956643469;5932.110183472481;5968.134620480033;5977.252843394575;5981.790637327695;5781.39295373986;5942.881142377149;6012.969935150318;6110.892920173598;6119.755209791614;5351.839444816662;5366.838994830152;5419.376771671269;5465.316835395575;5516.641418307797;4563.247064234397;5358.651687640291;5449.719315960766;5551.3893471718075;5584.994700953826;5361.839144825662;5384.542313903323;5432.158015507583;5435.891282174334;5491.7254137292975;4468.597826195625;5317.601179911522;5379.81170659029;5466.480684334982;5469.206964990054;5347.866303342409;5377.247185394048;5385.383386581478;5432.891342173134;5441.319835020622;5712.400197979236;5831.504322132624;5837.182794408786;5852.76588936442;5858.062709966385;5803.332616749086;5885.764569417218;6236.906446403307;6250.593711408744;6315.526335524854;6372.458341041017;6410.775622853178;6414.647194404306;6416.647084410356;6435.163428754232;6044.274687037584;6133.355997620275;6157.876842463125;6163.291221509539;6164.229471316085;5446.863828404322;6257;6326.272478665012;6362.6500542469685;6534.215894092739;6155.84610384744;6158.414950579688;6190.2571691397325;6249.343818899041;6270.655113968684;4698.976505117521;5332.200169974457;6408.743650254031;6427.614343139427;6446.967765161145;6298.968505157445;6319.873602527924;6321.1782468278825;6344.746210151635;6351.364863513611;6133.877322453615;6165.383461653889;6220.502359811204;6235.532335074888;6278.529110316653;4297.634701050446;5076.060928728197;5102.183650615883;5105.5149760772665;5208.270842082112;4544.88637784058;4561.817527298871;4565.0413413183605;4570.634349252052;4599.471060827981;6146.723397447036;6146.815595532141;6154.9384506154365;6160.691965401712;6172.135900973867;6176.505879529627;6185;6286.308506064329;6297.4332310091395;6372;6067.773029911539;6156.168917196211;6160.599561028519;6163.260408750889;6167.506599472032;5687.857803554862;5690.061139911951;5707.74315155819;5708.942910570924;5720.370759216483;5476.644018138808;6276.623402595859;6395.296517383177;6468.288488266381;6524.249711283168;5387.86530336729;6423.325550817284;6427.55007149495;6439.484841212598;6456.192975878015;6242.968785156045;6598.241202261609;6598.967005164944;6600.8349791255605;6619.801405957829;5253.605979551626;6088.4215999479975;6226.688665566613;6234.501239900706;6259.155014073224;6287.591306565058;6371.585846919936;6393.041043843461;6409.102725618324;6437.967810160919;6230.470410015199;6241.032639940817;6242.65665388408;6247.812565623039;6250.968745156245;6088.056351265561;6092.725827337692;6135.447809697149;6140.596275277727;6191.597546159665;6028.969855150872;6085.6348619083;6163.599366041193;6178.783742568989;6205.410486003822;5659.179418984307;5840.561957853263;6252.687365631791;6262.718177681924;6272.529560283088;5489.1217405214975;6008.639481631116;6161.969190154021;6202.317745047951;6209.751609935552 07:44:20 INFO - PROCESS | 1644 | |1;ext.html;710.8400609862779;733.3546479098394;800.4476910931456;936.2884208001916;939.0468674295587;37341;38037.66868159615;39255;39265.60734392655;39353.40969885454;38301.23397532048;38540.22919541615;38560.843174704736;38565.80717096411;38587.03532411684;7831.647575859071;7980.521168729866;10271.17830573556;10709.946450267738;10717.035466808025;10637.095846853026;10722.678319650424;10735.677929662084;10755.623553175641;10770.246082774209;10722.463876806129;10739.301945373525;10773.192010599236;10781.62264320749;10793.08258798003;10649.787004259872;10712.41081740504;10748.140148788156;10758.677239682822;10774.676759697222;10439.843402348972;10489.898560651098;10497.527611257436;10501.212409069274;10527;33743.493847592305;33753.49369759455;33816;33816.98549043533;33821.66178338234;3113.9065828025196;3681.9631803682155;3693.519842420468;3700.5374328208973;3855.807209639535;20213;24931;25667.743322566723;25732.87133564339;25878.094266700577;3034.332446861687;3054.8930787422332;3117.6414712308147;3162.130414136108;3177.0627664838926;272746;338916;341758;342090;344347.27826360957;42738.35892461616;42746.57253427426;42860.71417857469;42907.141857163304;42991.57008429938;45585.77207113943;45811;45828;45895.31157032647;45926.54073459223;12657.683557910941;12758.61724148277;12810.80783788244;12812.679683008;12856.357182141042;8914.73255802327;10802.945985270024;10873.673789786319;10888;10909.890901091047;9462.432254064779;9689.467079310705;11877.287362758263;11965.641030769091;11990;4394.560543945579;5451.48210919962;5471.917921231185;5512.448755124456;5523.1991361253195;960.7213907966611;1026.3585259212991;1130.2427373659668;1131.6265632341308;1134.1323887226279;25745.098921537658;25905.870470647526;26354.341141471617;26504;26525.602115968275;1090.8254679251281;1224.5407972010496;1234.4198226833373;1244.564402459132;1267.518343029643;13345.065845390729;13459.461621535225;13567.728645427036;13608.115472494454;13636.522721704692;343265;350340;350880;351628;352357;47691.523084769404;48324.758376207894;54988.72505637526;55469.1679624806;55496.612584684895;1352.5198554513204;1378.3659516622265;1388.7847383655549;1390.5619729785085;1391.87473127417;22181.669099853414;22824.65763013523;24543.263702088967;26402.339941501617;26785.732142678324;33230.667693323725;40233;40368.596314036484;40523.784286471455;40591.1881762369;401743;403142;403222;404425;404718;21604.89197554002;21867.45331366665;21924.780752192273;21946.012429440394;22022;37694.246115078095;38026.0493487665;38305;38463.615363846;38596;31650.366992659558;31996;32261.35477290488;32897.83551082229;33413.83293083519;32246.83876580602;32844;32881.506777398834;33052.52106092101;33865.66134338625;31410;31834.522482162323;32178.83910580432;32558.67441325651;32602;27858.804268615004;28820.855895721226;28926.710732893243;29021.85489072541;29352.706472935;17830.643387132444;19604.921729305024;21165.89417052905;22828.086876524754;22946.885265573565 07:44:20 INFO - PROCESS | 1644 | |2;jquery.html;366.57294252196186;383.19528989122864;386.96877731616524;395.1800014968939;425.59993606010346;13632.182069075863;13707;17700.91149544252;18744.906275468602;18908.338208162717;15100;20770.5292423791;21036.789632103675;21520.784792152073;22116.33650990469;6316.336784637616;6366.936330636692;6562.803115906507;6620.768273110442;6645.867082658344;6540.509461790361;6549.803505894831;6610.900836487456;6620.470362371023;6656.800295991104;5664.490195882369;6419.325970773071;6524.282328943837;6557.213134423876;6642.800715978528;6272.968635156841;6372.808815735535;6497.415232629062;6568.934310656904;6570.868582628321;6301.369863013708;6331.113644089831;6345.619262844244;6347.555671103025;6353.96823015882;25792.48415031689;25861.224163275132;26013.609795852888;26231.868840655676;26349.73650263492;2532.341591186305;2817.8309301441977;2828.123281782654;2835.4045650413436;2844.416894536615;12453.315067671274;15850;16026;16031.19844007795;16671.33314667412;1883.3690713610956;1904.1716853168916;1941.7572803399576;1943.6598595245775;1971.9605607887906;117964;120671;123570;124134.13798793025;124473;17159.399421020204;20280;20984.2720446341;21138.365849024554;21220.25729099474;28848.42303153967;34660.0140983785;34838.477422838674;35048.64951350505;35074.24628768602;9296.8605470918;9375.06249375057;9819.705408837746;10017.749556261151;10201;8114.837703245902;8124.309433698202;8273.958630206811;8296.792580185544;8298.792530186673;10176.694699159038;10481.3187142837;10521.368717876952;10610.20423468243;10618.787624247472;3489.0753950203216;4089.4683691119963;4121.97939010309;4136.414740787008;4143.751374917515;1042.5048102151418;1068.9572417103384;1072.9088027517591;1078.829470025016;1117.1118960426481;22266.99798509071;22785.202517912126;22839.657405138936;22860.771392285864;22901.427464313194;1087.9292845965144;1089.1667874076338;1092.7104317355906;1128.142611615162;1132.4620805117668;11606.76786464266;11866.88133118675;11998.220115692453;12050.638480845588;12144;666543;676711;697909;699242;705634;126471;130764;153364;160870;165068;801.997503121087;1036.0520124086377;1072.6966735416506;1080.3517889266377;1089.8147314956477;14932.552023439313;18623.90688046551;19152.233910643976;19821.504462388555;19966.800331997074;48089.75955120202;53955;62879.6856015717;63759.681201593696;63829.36170638234;83199.75200371878;86249;89308;90591;91859.54070229606;9377.531123443938;10676.160137408853;11225.4387280634;11595.652130436101;11696.239744416585;11552.884471155181;12423.44094515767;12596.433160507982;12705.745885082431;12755.298458584495;9385.63908233316;10730.517126729472;10882.455877206266;11240.55037798479;11452.3701196435;8493;10297.588096476356;10932.289401188897;11029.172812038969;11190.720231994264;10963.945180274366;11057.170712196457;11079.781224065508;11086.334819910831;11474;11751.588694395656;11960.64118076459;12060.276383417024;12599.118061735591;12938.935305323414;9819.45992970365;10354.378737275787;10724.873888241935;11624.94187529057;11663.650090497298 07:44:20 INFO - PROCESS | 1644 | |3;mootools.html;1365.8868022561612;1530.3343045775089;1551.0538571471407;1571.8349573294806;1586.9605408457458;10305.948470257648;10492.68521944341;10758.246922715412;10932;10946.781064378718;10645.041946224857;10859.511321990509;10861.6741497755;10891.945540272307;10896.128309735223;2303.7696230376955;2352.2943117064897;2355.1639168095335;2362.6337917622795;2367.6566897799808;2330.720313562375;2350.330155905567;2358.5282943411303;2365.9171928982487;2366.5858474766933;2328.37133973827;2329.4292898239955;2350.5063936573342;2354;2355.3287313115766;2315.976840231593;2317.9072837086496;2318.8376813623136;2337.3338598499518;2337.812974961999;2298.7931086202157;2343.367290831466;2360.362702070431;2368.9644655330185;2370.0282884017643;10652.467376631137;10664.360138391721;10745.355278683304;10749.946250268777;10750.086242669382;5491.450854914517;5632.436756324376;5778;5782;5809.593328467009;3298.208429976812;3492.6158122606494;3563.4832949222478;3576.998440436682;3640.9259268515852;1108.661858133269;1182.9408529573573;1207.0584943743806;1228.1280290993404;1245.785359274709;12975.416106275146;13158.868411315956;13299.26854023038;14182.787258191136;14383.35274912621;4706.10583989041;4758.881027974328;4817.662763606514;4889.3888263967;4892.339534162914;12569;12604.432800523999;12638.55765048219;12644.557440489538;12662.873371266354;5806.506446952057;5865.765369385262;5905.409459054146;5922.378150294243;5929.673867937305;5116.360454943132;5200.479952004769;5215.400228973641;5215.817446389358;5225.190095535198;3359.152466143611;3431.9828400857837;3644.4715516249994;3838.8272527736317;4061.4923134608175;1115.6039605939936;1137.732632831284;1139.128566646516;1149.683836944847;1152.193464574801;671.8679025841473;760.9809754756176;898.6585097662846;915.0803442540205;928.6935311347239;5883.294004719461;6179.814605561751;6324.083007963912;6405.910995130839;6506.772262970773;923.8337099322187;1005.3264312910368;1027.1423361493087;1032.5301987595626;1039.4594810698395;4061.903286112733;4070.9592904071174;4090.9181816363507;4164.250434921682;4235.78821058952;11065.889341106647;12796.872031279754;13198.868011319955;13228.14017088886;13262.403191856389;12642.115051946459;12876.420561074774;13123.934380328037;13168.934155329162;13181.340932953313;9822.214222862154;9846.60613575449;9856.408615482951;9969.351992120633;9995.450250236307;5086.364204474441;6133.50931925436;6189.226346706661;6332.3034466207755;6405.807825765234;10770.676879693621;11915.106367022681;11939.343336116597;11982.940085299866;12117.394130293625;11724.70688232801;11914.463849126983;12015.639530814089;12082.154249202824;12125.878741212828;8451.704190353308;8847.646094156162;8881.555922203994;9065.954670226607;9077.954610226907;9820.901790982;9978.950105249427;10037.247206459399;10047.698569042941;10072.798544029225;8447.915520844714;8811;8834.249088827522;8869.290456763314;8958.776030599287;8520.957395212985;8574.742757717278;8581.528015959182;8583.65665373378;8729.51987640686;7518.473706840468;7613.885791713018;7644.579548124684;7649.082110146818;7663.501872378278;5616.915746263891;5639.379668236409;5714.514266287246;5814.127880817911;5816.127580862735;5658.20785090096;5727.4845263926445;5900.616459930091;5935.673537955454;5988.820335389945 07:44:20 INFO - PROCESS | 1644 | |4;prototype.html;312.8123126124325;313.75840602735894;351.94896739972705;362.72251727428505;370.60530534980245;5032.421271553763;5376.182910133804;5495.598622351305;5709.257796486461;5739.368669446358;4583.977080114603;5320.733963301829;5440.836774896759;5531.861703457415;5618.803341883025;4863.343448634424;4864.635152363569;4875.440393793269;4880.999395123991;4882.292067650189;4320.925955541342;4631.9536804632025;4669.322948172513;4872.78072486739;4974.527419895104;4547.886302842422;4613.584777370036;4626.398568186131;4643.605293550052;4652.906192213501;4423.51341352451;4623.283391074388;4685.812567497296;4689.155951928668;4720.221163508017;4127.752334859918;4192.798780640158;4241.172971270604;4313.619641714653;4313.921519620095;5215.608829337777;5415.241866138744;5485.270016388854;5488;5507.339119305709;5357.901630165807;5373.194020896858;5390.1375779875425;5400.540954018913;5401.567874570025;5119.974400128012;5670.291213598301;6012.248468941382;6051.546134039964;6085.330613632497;4713.220068358136;4725.692829966041;4751.71489710615;4755.405574303212;4796;5056.418511871145;6341.714622841937;6351.523635727339;6380.393862583047;6380.425761681471;4333.090051089243;4381.364702118175;4822.493638168012;4842.225243960949;4919.508049195052;4506.355180359145;4538.364628951949;4716.764161791965;4923.532264434873;4989.376327959005;4795.328653988445;4905.779239934212;5283.7054921543695;5345.812896548602;5407.945920540744;5006.148954677714;5208.8958220835375;5608.887822243614;5939.316978547436;5969.970150149222;1361.1696864912396;1570.9396157593808;1613.3546581367311;1621.1894052973514;1621.9756703649455;1123.8651361836633;1146.1575741829915;1158.3397463445924;1207.076586411396;1231.4027696567157;5191.118928994444;5197.7401129943355;5198.622365073259;5216.217567364925;5386.91919621206;5063.822556300826;5432.859099589131;5536.640118392292;5766.702491939364;5880.970595146997;4394.890127746768;4591.77041147948;4833.679833679854;4932.482089380635;5311.548518375904;2825.5055365310986;3048.4055609156444;3073.602589979819;3077.569140320357;3170.5878235829196;5423.484768946943;5602.198900549725;5991.850203744942;6129.478994285447;6141.048137538689;5185.844424667266;5585.58108141891;5674.333063460167;5852.356240813507;5963.823204530315;5102.591792656579;5200.6619569727845;5539.842289894721;5753.1945527627;5997.970010149921;4488.169688607618;5040.6138311964505;5172.456892026434;5305.973470132779;5539.639923404966;5087.465816089258;5171.81898633546;5416.350037995308;5509.00837849191;5711.314642242957;5300.893982120258;5391.973040134774;5737.225474561048;6056.636601803906;6208.9689551551955;3976.547814992575;4174.998000479803;4269.530351661253;4553.931691024571;4616.769161541843;5200.739963001759;5321.574274057989;5853.868180523399;5879.412058794086;6012.158297838394;4884.511548845087;5162.741862906764;5660.462256085665;5855.736491857791;5985.396352586638;4936.531029552187;5913.822585322448;5928.399332180201;5940.1683764272175;5992.940070599413;5036.395632524121;5556.416576259596;5560;5703.942960570341;5729.8567535811935;5725.255716756795;5981.312149102909;5984.341722410444;6007.626987340063;6024.608400454132;5500;5574.637648552831;5682.6306290091015;5699.344575373885;5881.705914704162 07:44:20 INFO - PROCESS | 1644 | |5;yui.html;641.0160403780195;687.8968154776786;764.2281295891152;807.5074204735113;834.4200780457583;14909.701805963889;15527.146006969611;15536.300866461008;15833.287502062407;16599.75501837361;16549.503514894543;16888.662226755456;16894.831051689478;16934.661306773858;17016.659666806656;835.7994081420446;937.4281688170211;953.8569214617828;961.0341606685272;962.5668449197854;746.231381676871;950.2682934140742;952.2619969523605;956.6795123633591;960.6493629825134;1004.5178314409088;1004.9547770350315;1007.7631756537207;1009.1523120578712;1010.8685870836817;881.2817553693783;994.2841154368842;995.6714284286204;996.4021587047786;1005.6178652112187;742.6064185981438;743.6393349225622;743.7322563877;744.151667099507;744.1814004594956;11477.942610286895;15340.463083792014;15720.21398930049;15816.130112843788;16318.347266109342;13908.58274251764;14180.716385672229;14187.78718319226;14257.78613320801;14288.357023933948;11437.485313160927;11534.826977595343;11623.418829058597;11741.88258117425;12051.276923384625;3595.1731101846462;3626.909327266813;3638.126849556114;3707.0917625181733;3716.591174970751;98478.5076074615;102987.45518817224;104515.4774226124;105128.94871051192;105235;8494.74515764528;9314.835645544306;10499.317544359745;10563.20775941792;10770.730731731614;21482.033308501155;21565.67651485229;21898.89050554737;22021.449463763533;22103.889480552494;7400.5189662671755;7634.198409166957;7661;7712.691492340356;7748.961255193688;4067.877963661095;4142.8599868606625;4238.576142385737;4337.1976184405985;4357.782110894443;3356.647552007004;3415.0608582639975;3419;3454.7408944329277;3455.4989526519016;543.8232574413331;625.587112505744;632.5129650169343;669.2003056347618;695.0581961442274;639.6865535887474;652.6475703120262;666.1007639686482;692.4529621598881;698.26333218454;9435.669751558662;9815.329626687875;9856.753581160385;10005.14956228714;10215.846762298572;629.8536663272891;677.5866721300004;686.4165459359505;697.435077587156;702.9367356937798;4641.675082744176;4774.212254977959;4776.283557466339;4831.855044348676;4836.46798852126;79580.60209698914;100445;103212;104241;105369.94630053602;64468;72053.639731801;73461;75749.72750817484;89040;60060;72953.90569141466;73032;73299;74115.62942185362;9705.398534469698;10135.923491927173;10344.194539130644;10495.527701253615;10526.789464210826;36372.27255454929;48158.5184148154;48314.75842620905;48641.75679121582;48879.26681099715;60262.39737602568;61933.69033154805;64896.67551662211;65762.67118664568;68421;12462.626121216377;12613.74772504563;12631.557895473614;12675.80986285188;13171.538996135088;11436.428178590872;14206.715865682421;14306;14436.422543098579;14699.706005879609;12307.138500305251;13291.003174762125;13346.265955372548;13358.799618005543;13635.65910852197;12920.676983075498;13225.537106201622;13253.536126235536;13600.795988060385;13784.517541885985;12843.458784945493;12917.935410323264;12927.676808079497;13209.339533023118;13399.866001340251;9420.905790942003;10657.893421066;10670.253082284165;10777.407242601732;11427.257228280136;9751.804963900824;10440.776418983978;10626.681199564026;10702.411367374869;10709.946450267698 07:44:20 INFO - PROCESS | 1644 | __end_tp_report 07:44:20 INFO - PROCESS | 1644 | __start_cc_report 07:44:20 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,432 07:44:20 INFO - PROCESS | 1644 | __end_cc_report 07:44:20 INFO - PROCESS | 1644 | __startTimestamp1488383060633__endTimestamp 07:44:20 INFO - PROCESS | 1644 | 07:44:20 INFO - PROCESS | 1644 | ------- Summary: start ------- 07:44:20 INFO - PROCESS | 1644 | Number of tests: 6 07:44:20 INFO - PROCESS | 1644 | 07:44:20 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5852.84 Median:6140.60 stddev:654.15 (10.7%) stddev-sans-first:628.25 07:44:20 INFO - PROCESS | 1644 | Values: 3442.9 3472.7 3478.6 3486.3 3490.0 5725.5 5932.1 5968.1 5977.3 5981.8 5781.4 5942.9 6013.0 6110.9 6119.8 5351.8 5366.8 5419.4 5465.3 5516.6 4563.2 5358.7 5449.7 5551.4 5585.0 5361.8 5384.5 5432.2 5435.9 5491.7 4468.6 5317.6 5379.8 5466.5 5469.2 5347.9 5377.2 5385.4 5432.9 5441.3 5712.4 5831.5 5837.2 5852.8 5858.1 5803.3 5885.8 6236.9 6250.6 6315.5 6372.5 6410.8 6414.6 6416.6 6435.2 6044.3 6133.4 6157.9 6163.3 6164.2 5446.9 6257.0 6326.3 6362.7 6534.2 6155.8 6158.4 6190.3 6249.3 6270.7 4699.0 5332.2 6408.7 6427.6 6447.0 6299.0 6319.9 6321.2 6344.7 6351.4 6133.9 6165.4 6220.5 6235.5 6278.5 4297.6 5076.1 5102.2 5105.5 5208.3 4544.9 4561.8 4565.0 4570.6 4599.5 6146.7 6146.8 6154.9 6160.7 6172.1 6176.5 6185.0 6286.3 6297.4 6372.0 6067.8 6156.2 6160.6 6163.3 6167.5 5687.9 5690.1 5707.7 5708.9 5720.4 5476.6 6276.6 6395.3 6468.3 6524.2 5387.9 6423.3 6427.6 6439.5 6456.2 6243.0 6598.2 6599.0 6600.8 6619.8 5253.6 6088.4 6226.7 6234.5 6259.2 6287.6 6371.6 6393.0 6409.1 6438.0 6230.5 6241.0 6242.7 6247.8 6251.0 6088.1 6092.7 6135.4 6140.6 6191.6 6029.0 6085.6 6163.6 6178.8 6205.4 5659.2 5840.6 6252.7 6262.7 6272.5 5489.1 6008.6 6162.0 6202.3 6209.8 07:44:20 INFO - PROCESS | 1644 | 07:44:20 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:49197.68 Median:17406.25 stddev:95728.13 (550.0%) stddev-sans-first:95927.45 07:44:20 INFO - PROCESS | 1644 | Values: 710.8 733.4 800.4 936.3 939.0 37341.0 38037.7 39255.0 39265.6 39353.4 38301.2 38540.2 38560.8 38565.8 38587.0 7831.6 7980.5 10271.2 10709.9 10717.0 10637.1 10722.7 10735.7 10755.6 10770.2 10722.5 10739.3 10773.2 10781.6 10793.1 10649.8 10712.4 10748.1 10758.7 10774.7 10439.8 10489.9 10497.5 10501.2 10527.0 33743.5 33753.5 33816.0 33817.0 33821.7 3113.9 3682.0 3693.5 3700.5 3855.8 20213.0 24931.0 25667.7 25732.9 25878.1 3034.3 3054.9 3117.6 3162.1 3177.1 272746.0 338916.0 341758.0 342090.0 344347.3 42738.4 42746.6 42860.7 42907.1 42991.6 45585.8 45811.0 45828.0 45895.3 45926.5 12657.7 12758.6 12810.8 12812.7 12856.4 8914.7 10802.9 10873.7 10888.0 10909.9 9462.4 9689.5 11877.3 11965.6 11990.0 4394.6 5451.5 5471.9 5512.4 5523.2 960.7 1026.4 1130.2 1131.6 1134.1 25745.1 25905.9 26354.3 26504.0 26525.6 1090.8 1224.5 1234.4 1244.6 1267.5 13345.1 13459.5 13567.7 13608.1 13636.5 343265.0 350340.0 350880.0 351628.0 352357.0 47691.5 48324.8 54988.7 55469.2 55496.6 1352.5 1378.4 1388.8 1390.6 1391.9 22181.7 22824.7 24543.3 26402.3 26785.7 33230.7 40233.0 40368.6 40523.8 40591.2 401743.0 403142.0 403222.0 404425.0 404718.0 21604.9 21867.5 21924.8 21946.0 22022.0 37694.2 38026.0 38305.0 38463.6 38596.0 31650.4 31996.0 32261.4 32897.8 33413.8 32246.8 32844.0 32881.5 33052.5 33865.7 31410.0 31834.5 32178.8 32558.7 32602.0 27858.8 28820.9 28926.7 29021.9 29352.7 17830.6 19604.9 21165.9 22828.1 22946.9 07:44:20 INFO - PROCESS | 1644 | 07:44:20 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:40061.21 Median:20877.40 stddev:114720.70 (549.5%) stddev-sans-first:115003.80 07:44:20 INFO - PROCESS | 1644 | Values: 366.6 383.2 387.0 395.2 425.6 13632.2 13707.0 17700.9 18744.9 18908.3 15100.0 20770.5 21036.8 21520.8 22116.3 6316.3 6366.9 6562.8 6620.8 6645.9 6540.5 6549.8 6610.9 6620.5 6656.8 5664.5 6419.3 6524.3 6557.2 6642.8 6273.0 6372.8 6497.4 6568.9 6570.9 6301.4 6331.1 6345.6 6347.6 6354.0 25792.5 25861.2 26013.6 26231.9 26349.7 2532.3 2817.8 2828.1 2835.4 2844.4 12453.3 15850.0 16026.0 16031.2 16671.3 1883.4 1904.2 1941.8 1943.7 1972.0 117964.0 120671.0 123570.0 124134.1 124473.0 17159.4 20280.0 20984.3 21138.4 21220.3 28848.4 34660.0 34838.5 35048.6 35074.2 9296.9 9375.1 9819.7 10017.7 10201.0 8114.8 8124.3 8274.0 8296.8 8298.8 10176.7 10481.3 10521.4 10610.2 10618.8 3489.1 4089.5 4122.0 4136.4 4143.8 1042.5 1069.0 1072.9 1078.8 1117.1 22267.0 22785.2 22839.7 22860.8 22901.4 1087.9 1089.2 1092.7 1128.1 1132.5 11606.8 11866.9 11998.2 12050.6 12144.0 666543.0 676711.0 697909.0 699242.0 705634.0 126471.0 130764.0 153364.0 160870.0 165068.0 802.0 1036.1 1072.7 1080.4 1089.8 14932.6 18623.9 19152.2 19821.5 19966.8 48089.8 53955.0 62879.7 63759.7 63829.4 83199.8 86249.0 89308.0 90591.0 91859.5 9377.5 10676.2 11225.4 11595.7 11696.2 11552.9 12423.4 12596.4 12705.7 12755.3 9385.6 10730.5 10882.5 11240.6 11452.4 8493.0 10297.6 10932.3 11029.2 11190.7 10963.9 11057.2 11079.8 11086.3 11474.0 11751.6 11960.6 12060.3 12599.1 12938.9 9819.5 10354.4 10724.9 11624.9 11663.7 07:44:20 INFO - PROCESS | 1644 | 07:44:20 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:6550.04 Median:2369.50 stddev:4032.17 (170.2%) stddev-sans-first:4024.66 07:44:20 INFO - PROCESS | 1644 | Values: 1365.9 1530.3 1551.1 1571.8 1587.0 10305.9 10492.7 10758.2 10932.0 10946.8 10645.0 10859.5 10861.7 10891.9 10896.1 2303.8 2352.3 2355.2 2362.6 2367.7 2330.7 2350.3 2358.5 2365.9 2366.6 2328.4 2329.4 2350.5 2354.0 2355.3 2316.0 2317.9 2318.8 2337.3 2337.8 2298.8 2343.4 2360.4 2369.0 2370.0 10652.5 10664.4 10745.4 10749.9 10750.1 5491.5 5632.4 5778.0 5782.0 5809.6 3298.2 3492.6 3563.5 3577.0 3640.9 1108.7 1182.9 1207.1 1228.1 1245.8 12975.4 13158.9 13299.3 14182.8 14383.4 4706.1 4758.9 4817.7 4889.4 4892.3 12569.0 12604.4 12638.6 12644.6 12662.9 5806.5 5865.8 5905.4 5922.4 5929.7 5116.4 5200.5 5215.4 5215.8 5225.2 3359.2 3432.0 3644.5 3838.8 4061.5 1115.6 1137.7 1139.1 1149.7 1152.2 671.9 761.0 898.7 915.1 928.7 5883.3 6179.8 6324.1 6405.9 6506.8 923.8 1005.3 1027.1 1032.5 1039.5 4061.9 4071.0 4090.9 4164.3 4235.8 11065.9 12796.9 13198.9 13228.1 13262.4 12642.1 12876.4 13123.9 13168.9 13181.3 9822.2 9846.6 9856.4 9969.4 9995.5 5086.4 6133.5 6189.2 6332.3 6405.8 10770.7 11915.1 11939.3 11982.9 12117.4 11724.7 11914.5 12015.6 12082.2 12125.9 8451.7 8847.6 8881.6 9066.0 9078.0 9820.9 9979.0 10037.2 10047.7 10072.8 8447.9 8811.0 8834.2 8869.3 8958.8 8521.0 8574.7 8581.5 8583.7 8729.5 7518.5 7613.9 7644.6 7649.1 7663.5 5616.9 5639.4 5714.5 5814.1 5816.1 5658.2 5727.5 5900.6 5935.7 5988.8 07:44:20 INFO - PROCESS | 1644 | 07:44:20 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4851.79 Median:5199.64 stddev:1331.37 (25.6%) stddev-sans-first:1290.78 07:44:20 INFO - PROCESS | 1644 | Values: 312.8 313.8 351.9 362.7 370.6 5032.4 5376.2 5495.6 5709.3 5739.4 4584.0 5320.7 5440.8 5531.9 5618.8 4863.3 4864.6 4875.4 4881.0 4882.3 4320.9 4632.0 4669.3 4872.8 4974.5 4547.9 4613.6 4626.4 4643.6 4652.9 4423.5 4623.3 4685.8 4689.2 4720.2 4127.8 4192.8 4241.2 4313.6 4313.9 5215.6 5415.2 5485.3 5488.0 5507.3 5357.9 5373.2 5390.1 5400.5 5401.6 5120.0 5670.3 6012.2 6051.5 6085.3 4713.2 4725.7 4751.7 4755.4 4796.0 5056.4 6341.7 6351.5 6380.4 6380.4 4333.1 4381.4 4822.5 4842.2 4919.5 4506.4 4538.4 4716.8 4923.5 4989.4 4795.3 4905.8 5283.7 5345.8 5407.9 5006.1 5208.9 5608.9 5939.3 5970.0 1361.2 1570.9 1613.4 1621.2 1622.0 1123.9 1146.2 1158.3 1207.1 1231.4 5191.1 5197.7 5198.6 5216.2 5386.9 5063.8 5432.9 5536.6 5766.7 5881.0 4394.9 4591.8 4833.7 4932.5 5311.5 2825.5 3048.4 3073.6 3077.6 3170.6 5423.5 5602.2 5991.9 6129.5 6141.0 5185.8 5585.6 5674.3 5852.4 5963.8 5102.6 5200.7 5539.8 5753.2 5998.0 4488.2 5040.6 5172.5 5306.0 5539.6 5087.5 5171.8 5416.4 5509.0 5711.3 5300.9 5392.0 5737.2 6056.6 6209.0 3976.5 4175.0 4269.5 4553.9 4616.8 5200.7 5321.6 5853.9 5879.4 6012.2 4884.5 5162.7 5660.5 5855.7 5985.4 4936.5 5913.8 5928.4 5940.2 5992.9 5036.4 5556.4 5560.0 5703.9 5729.9 5725.3 5981.3 5984.3 6007.6 6024.6 5500.0 5574.6 5682.6 5699.3 5881.7 07:44:20 INFO - PROCESS | 1644 | 07:44:20 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:19476.57 Median:3525.34 stddev:27419.37 (777.8%) stddev-sans-first:27459.81 07:44:20 INFO - PROCESS | 1644 | Values: 641.0 687.9 764.2 807.5 834.4 14909.7 15527.1 15536.3 15833.3 16599.8 16549.5 16888.7 16894.8 16934.7 17016.7 835.8 937.4 953.9 961.0 962.6 746.2 950.3 952.3 956.7 960.6 1004.5 1005.0 1007.8 1009.2 1010.9 881.3 994.3 995.7 996.4 1005.6 742.6 743.6 743.7 744.2 744.2 11477.9 15340.5 15720.2 15816.1 16318.3 13908.6 14180.7 14187.8 14257.8 14288.4 11437.5 11534.8 11623.4 11741.9 12051.3 3595.2 3626.9 3638.1 3707.1 3716.6 98478.5 102987.5 104515.5 105128.9 105235.0 8494.7 9314.8 10499.3 10563.2 10770.7 21482.0 21565.7 21898.9 22021.4 22103.9 7400.5 7634.2 7661.0 7712.7 7749.0 4067.9 4142.9 4238.6 4337.2 4357.8 3356.6 3415.1 3419.0 3454.7 3455.5 543.8 625.6 632.5 669.2 695.1 639.7 652.6 666.1 692.5 698.3 9435.7 9815.3 9856.8 10005.1 10215.8 629.9 677.6 686.4 697.4 702.9 4641.7 4774.2 4776.3 4831.9 4836.5 79580.6 100445.0 103212.0 104241.0 105369.9 64468.0 72053.6 73461.0 75749.7 89040.0 60060.0 72953.9 73032.0 73299.0 74115.6 9705.4 10135.9 10344.2 10495.5 10526.8 36372.3 48158.5 48314.8 48641.8 48879.3 60262.4 61933.7 64896.7 65762.7 68421.0 12462.6 12613.7 12631.6 12675.8 13171.5 11436.4 14206.7 14306.0 14436.4 14699.7 12307.1 13291.0 13346.3 13358.8 13635.7 12920.7 13225.5 13253.5 13600.8 13784.5 12843.5 12917.9 12927.7 13209.3 13399.9 9420.9 10657.9 10670.3 10777.4 11427.3 9751.8 10440.8 10626.7 10702.4 10709.9 07:44:20 INFO - PROCESS | 1644 | -------- Summary: end -------- 07:44:20 INFO - PROCESS | 1644 | 07:44:20 INFO - PROCESS | 1644 | 1488383060660 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 07:44:21 INFO - TEST-INFO | 1644: exit 0 07:44:21 INFO - TEST-OK | dromaeo_css | took 1118134ms 07:44:21 INFO - TEST-START | kraken 07:44:21 INFO - operating with platform_type : mac_ 07:44:21 INFO - Initialising browser for kraken test... 07:44:21 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpescZLk/profile http://localhost:49237/getInfo.html) 07:44:21 INFO - PROCESS | 1657 | 1488383061855 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:44:21 INFO - PROCESS | 1657 | 1488383061856 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 07:44:21 INFO - PROCESS | 1657 | 1488383061897 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 07:44:24 INFO - PROCESS | 1657 | 2017-03-01 07:44:24.332 plugin-container[1658:10230] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 07:44:24 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:44:24 INFO - PROCESS | 1657 | 2017-03-01 07:44:24.335 plugin-container[1658:10230] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 07:44:24 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:44:24 INFO - PROCESS | 1657 | 2017-03-01 07:44:24.335 plugin-container[1658:10230] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:44:24 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 07:44:24 INFO - PROCESS | 1657 | colorDepth:24 07:44:24 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 07:44:24 INFO - PROCESS | 1657 | __metrics 07:44:24 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 07:44:24 INFO - PROCESS | 1657 | 1488383064498 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 07:44:25 INFO - TEST-INFO | 1657: exit 0 07:44:25 INFO - Browser initialized. 07:44:25 INFO - Running cycle 1/1 for kraken test... 07:44:25 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpescZLk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:44:25 INFO - PROCESS | 1659 | 1488383065503 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:44:25 INFO - PROCESS | 1659 | 1488383065503 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 07:44:27 INFO - PROCESS | 1659 | 2017-03-01 07:44:27.606 plugin-container[1660:10353] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8733, name = 'com.apple.tsm.portname' 07:44:27 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:44:27 INFO - PROCESS | 1659 | 2017-03-01 07:44:27.606 plugin-container[1660:10353] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 07:44:27 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:44:27 INFO - PROCESS | 1659 | 2017-03-01 07:44:27.606 plugin-container[1660:10353] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:44:37 INFO - PROCESS | 1659 | RSS: Main: 226418688 07:44:37 INFO - PROCESS | 1659 | 07:44:40 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 07:44:41 INFO - PROCESS | 1659 | RSS: Main: 240955392 07:44:41 INFO - PROCESS | 1659 | 07:44:43 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 07:44:44 INFO - PROCESS | 1659 | RSS: Main: 249155584 07:44:44 INFO - PROCESS | 1659 | 07:44:47 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 07:44:48 INFO - PROCESS | 1659 | RSS: Main: 251699200 07:44:48 INFO - PROCESS | 1659 | 07:44:50 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 07:44:51 INFO - PROCESS | 1659 | RSS: Main: 253980672 07:44:51 INFO - PROCESS | 1659 | 07:44:53 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:44:54 INFO - PROCESS | 1659 | RSS: Main: 254558208 07:44:54 INFO - PROCESS | 1659 | 07:44:57 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 07:44:57 INFO - PROCESS | 1659 | RSS: Main: 259948544 07:44:57 INFO - PROCESS | 1659 | 07:45:01 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 07:45:02 INFO - PROCESS | 1659 | RSS: Main: 259235840 07:45:02 INFO - PROCESS | 1659 | 07:45:05 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 07:45:06 INFO - PROCESS | 1659 | RSS: Main: 256561152 07:45:06 INFO - PROCESS | 1659 | 07:45:07 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:45:08 INFO - PROCESS | 1659 | RSS: Main: 257622016 07:45:08 INFO - PROCESS | 1659 | 07:45:10 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:45:11 INFO - PROCESS | 1659 | RSS: Main: 257966080 07:45:11 INFO - PROCESS | 1659 | 07:45:13 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:45:13 INFO - PROCESS | 1659 | RSS: Main: 258084864 07:45:13 INFO - PROCESS | 1659 | 07:45:16 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:45:17 INFO - PROCESS | 1659 | RSS: Main: 258383872 07:45:17 INFO - PROCESS | 1659 | 07:45:20 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:45:21 INFO - PROCESS | 1659 | RSS: Main: 258224128 07:45:21 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 07:45:23 INFO - PROCESS | 1659 | RSS: Main: 261636096 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | __start_tp_report 07:45:23 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 07:45:23 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 07:45:23 INFO - PROCESS | 1659 | |i|pagename|runs| 07:45:23 INFO - PROCESS | 1659 | |0;ai-astar;108;119;140;120;119;118;117;119;118;120 07:45:23 INFO - PROCESS | 1659 | |1;audio-beat-detection;177;145;143;147;113;113;113;113;133;114 07:45:23 INFO - PROCESS | 1659 | |2;audio-dft;201;205;214;224;196;198;196;213;193;231 07:45:23 INFO - PROCESS | 1659 | |3;audio-fft;85;60;60;86;62;60;60;77;100;81 07:45:23 INFO - PROCESS | 1659 | |4;audio-oscillator;113;100;72;97;103;101;91;70;71;85 07:45:23 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;114;90;90;124;86;135;84;94;134;77 07:45:23 INFO - PROCESS | 1659 | |6;imaging-darkroom;96;124;98;99;99;96;99;139;122;151 07:45:23 INFO - PROCESS | 1659 | |7;imaging-desaturate;98;144;151;92;89;142;147;95;92;91 07:45:23 INFO - PROCESS | 1659 | |8;json-parse-financial;65;66;81;101;99;100;72;67;67;95 07:45:23 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;58;64;59;71;66;64;58;57;57;59 07:45:23 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;91;91;89;87;88;89;91;90;105;107 07:45:23 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;144;143;140;143;141;143;130;153;206;160 07:45:23 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;167;234;179;228;163;165;202;232;195;177 07:45:23 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;73;69;69;73;79;92;91;75;68;69 07:45:23 INFO - PROCESS | 1659 | __end_tp_report 07:45:23 INFO - PROCESS | 1659 | __start_cc_report 07:45:23 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1022 07:45:23 INFO - PROCESS | 1659 | __end_cc_report 07:45:23 INFO - PROCESS | 1659 | __startTimestamp1488383123595__endTimestamp 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | ------- Summary: start ------- 07:45:23 INFO - PROCESS | 1659 | Number of tests: 14 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:119.80 Median:119.00 stddev:7.91 (6.6%) stddev-sans-first:7.15 07:45:23 INFO - PROCESS | 1659 | Values: 108.0 119.0 140.0 120.0 119.0 118.0 117.0 119.0 118.0 120.0 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:138.00 stddev:21.86 (15.8%) stddev-sans-first:15.65 07:45:23 INFO - PROCESS | 1659 | Values: 177.0 145.0 143.0 147.0 113.0 113.0 113.0 113.0 133.0 114.0 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:207.10 Median:209.00 stddev:12.95 (6.2%) stddev-sans-first:13.54 07:45:23 INFO - PROCESS | 1659 | Values: 201.0 205.0 214.0 224.0 196.0 198.0 196.0 213.0 193.0 231.0 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:73.10 Median:69.50 stddev:14.60 (21.0%) stddev-sans-first:14.84 07:45:23 INFO - PROCESS | 1659 | Values: 85.0 60.0 60.0 86.0 62.0 60.0 60.0 77.0 100.0 81.0 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:71.50 stddev:15.20 (21.3%) stddev-sans-first:13.72 07:45:23 INFO - PROCESS | 1659 | Values: 113.0 100.0 72.0 97.0 103.0 101.0 91.0 70.0 71.0 85.0 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:102.80 Median:85.00 stddev:21.84 (25.7%) stddev-sans-first:22.78 07:45:23 INFO - PROCESS | 1659 | Values: 114.0 90.0 90.0 124.0 86.0 135.0 84.0 94.0 134.0 77.0 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:112.30 Median:97.00 stddev:20.30 (20.9%) stddev-sans-first:20.65 07:45:23 INFO - PROCESS | 1659 | Values: 96.0 124.0 98.0 99.0 99.0 96.0 99.0 139.0 122.0 151.0 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:114.10 Median:91.50 stddev:27.65 (30.2%) stddev-sans-first:28.71 07:45:23 INFO - PROCESS | 1659 | Values: 98.0 144.0 151.0 92.0 89.0 142.0 147.0 95.0 92.0 91.0 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:81.30 Median:69.50 stddev:15.76 (22.7%) stddev-sans-first:15.57 07:45:23 INFO - PROCESS | 1659 | Values: 65.0 66.0 81.0 101.0 99.0 100.0 72.0 67.0 67.0 95.0 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:61.30 Median:61.50 stddev:4.72 (7.7%) stddev-sans-first:4.85 07:45:23 INFO - PROCESS | 1659 | Values: 58.0 64.0 59.0 71.0 66.0 64.0 58.0 57.0 57.0 59.0 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:92.80 Median:89.50 stddev:7.10 (7.9%) stddev-sans-first:7.50 07:45:23 INFO - PROCESS | 1659 | Values: 91.0 91.0 89.0 87.0 88.0 89.0 91.0 90.0 105.0 107.0 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:143.50 stddev:21.10 (14.7%) stddev-sans-first:22.26 07:45:23 INFO - PROCESS | 1659 | Values: 144.0 143.0 140.0 143.0 141.0 143.0 130.0 153.0 206.0 160.0 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.20 Median:198.50 stddev:28.50 (14.4%) stddev-sans-first:28.48 07:45:23 INFO - PROCESS | 1659 | Values: 167.0 234.0 179.0 228.0 163.0 165.0 202.0 232.0 195.0 177.0 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.80 Median:74.00 stddev:8.94 (12.1%) stddev-sans-first:9.43 07:45:23 INFO - PROCESS | 1659 | Values: 73.0 69.0 69.0 73.0 79.0 92.0 91.0 75.0 68.0 69.0 07:45:23 INFO - PROCESS | 1659 | -------- Summary: end -------- 07:45:23 INFO - PROCESS | 1659 | 07:45:23 INFO - PROCESS | 1659 | 1488383123627 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 07:45:24 INFO - TEST-INFO | 1659: exit 0 07:45:24 INFO - TEST-OK | kraken | took 63228ms 07:45:24 INFO - SUITE-END | took 1181s 07:45:24 INFO - Completed test suite (00:19:41) 07:45:24 INFO - javascript benchmark 07:45:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7581.535169051129, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3442.913927151821, 3472.7221822254205, 3478.617352091268, 3486.3375958567863, 3490.0, 5725.541956643469, 5932.110183472481, 5968.134620480033, 5977.252843394575, 5981.790637327695, 5781.39295373986, 5942.881142377149, 6012.969935150318, 6110.892920173598, 6119.755209791614, 5351.839444816662, 5366.838994830152, 5419.376771671269, 5465.316835395575, 5516.641418307797, 4563.247064234397, 5358.651687640291, 5449.719315960766, 5551.3893471718075, 5584.994700953826, 5361.839144825662, 5384.542313903323, 5432.158015507583, 5435.891282174334, 5491.7254137292975, 4468.597826195625, 5317.601179911522, 5379.81170659029, 5466.480684334982, 5469.206964990054, 5347.866303342409, 5377.247185394048, 5385.383386581478, 5432.891342173134, 5441.319835020622, 5712.400197979236, 5831.504322132624, 5837.182794408786, 5852.76588936442, 5858.062709966385, 5803.332616749086, 5885.764569417218, 6236.906446403307, 6250.593711408744, 6315.526335524854, 6372.458341041017, 6410.775622853178, 6414.647194404306, 6416.647084410356, 6435.163428754232, 6044.274687037584, 6133.355997620275, 6157.876842463125, 6163.291221509539, 6164.229471316085, 5446.863828404322, 6257.0, 6326.272478665012, 6362.6500542469685, 6534.215894092739, 6155.84610384744, 6158.414950579688, 6190.2571691397325, 6249.343818899041, 6270.655113968684, 4698.976505117521, 5332.200169974457, 6408.743650254031, 6427.614343139427, 6446.967765161145, 6298.968505157445, 6319.873602527924, 6321.1782468278825, 6344.746210151635, 6351.364863513611, 6133.877322453615, 6165.383461653889, 6220.502359811204, 6235.532335074888, 6278.529110316653, 4297.634701050446, 5076.060928728197, 5102.183650615883, 5105.5149760772665, 5208.270842082112, 4544.88637784058, 4561.817527298871, 4565.0413413183605, 4570.634349252052, 4599.471060827981, 6146.723397447036, 6146.815595532141, 6154.9384506154365, 6160.691965401712, 6172.135900973867, 6176.505879529627, 6185.0, 6286.308506064329, 6297.4332310091395, 6372.0, 6067.773029911539, 6156.168917196211, 6160.599561028519, 6163.260408750889, 6167.506599472032, 5687.857803554862, 5690.061139911951, 5707.74315155819, 5708.942910570924, 5720.370759216483, 5476.644018138808, 6276.623402595859, 6395.296517383177, 6468.288488266381, 6524.249711283168, 5387.86530336729, 6423.325550817284, 6427.55007149495, 6439.484841212598, 6456.192975878015, 6242.968785156045, 6598.241202261609, 6598.967005164944, 6600.8349791255605, 6619.801405957829, 5253.605979551626, 6088.4215999479975, 6226.688665566613, 6234.501239900706, 6259.155014073224, 6287.591306565058, 6371.585846919936, 6393.041043843461, 6409.102725618324, 6437.967810160919, 6230.470410015199, 6241.032639940817, 6242.65665388408, 6247.812565623039, 6250.968745156245, 6088.056351265561, 6092.725827337692, 6135.447809697149, 6140.596275277727, 6191.597546159665, 6028.969855150872, 6085.6348619083, 6163.599366041193, 6178.783742568989, 6205.410486003822, 5659.179418984307, 5840.561957853263, 6252.687365631791, 6262.718177681924, 6272.529560283088, 5489.1217405214975, 6008.639481631116, 6161.969190154021, 6202.317745047951, 6209.751609935552], "value": 5814.656766605634, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.8400609862779, 733.3546479098394, 800.4476910931456, 936.2884208001916, 939.0468674295587, 37341.0, 38037.66868159615, 39255.0, 39265.60734392655, 39353.40969885454, 38301.23397532048, 38540.22919541615, 38560.843174704736, 38565.80717096411, 38587.03532411684, 7831.647575859071, 7980.521168729866, 10271.17830573556, 10709.946450267738, 10717.035466808025, 10637.095846853026, 10722.678319650424, 10735.677929662084, 10755.623553175641, 10770.246082774209, 10722.463876806129, 10739.301945373525, 10773.192010599236, 10781.62264320749, 10793.08258798003, 10649.787004259872, 10712.41081740504, 10748.140148788156, 10758.677239682822, 10774.676759697222, 10439.843402348972, 10489.898560651098, 10497.527611257436, 10501.212409069274, 10527.0, 33743.493847592305, 33753.49369759455, 33816.0, 33816.98549043533, 33821.66178338234, 3113.9065828025196, 3681.9631803682155, 3693.519842420468, 3700.5374328208973, 3855.807209639535, 20213.0, 24931.0, 25667.743322566723, 25732.87133564339, 25878.094266700577, 3034.332446861687, 3054.8930787422332, 3117.6414712308147, 3162.130414136108, 3177.0627664838926, 272746.0, 338916.0, 341758.0, 342090.0, 344347.27826360957, 42738.35892461616, 42746.57253427426, 42860.71417857469, 42907.141857163304, 42991.57008429938, 45585.77207113943, 45811.0, 45828.0, 45895.31157032647, 45926.54073459223, 12657.683557910941, 12758.61724148277, 12810.80783788244, 12812.679683008, 12856.357182141042, 8914.73255802327, 10802.945985270024, 10873.673789786319, 10888.0, 10909.890901091047, 9462.432254064779, 9689.467079310705, 11877.287362758263, 11965.641030769091, 11990.0, 4394.560543945579, 5451.48210919962, 5471.917921231185, 5512.448755124456, 5523.1991361253195, 960.7213907966611, 1026.3585259212991, 1130.2427373659668, 1131.6265632341308, 1134.1323887226279, 25745.098921537658, 25905.870470647526, 26354.341141471617, 26504.0, 26525.602115968275, 1090.8254679251281, 1224.5407972010496, 1234.4198226833373, 1244.564402459132, 1267.518343029643, 13345.065845390729, 13459.461621535225, 13567.728645427036, 13608.115472494454, 13636.522721704692, 343265.0, 350340.0, 350880.0, 351628.0, 352357.0, 47691.523084769404, 48324.758376207894, 54988.72505637526, 55469.1679624806, 55496.612584684895, 1352.5198554513204, 1378.3659516622265, 1388.7847383655549, 1390.5619729785085, 1391.87473127417, 22181.669099853414, 22824.65763013523, 24543.263702088967, 26402.339941501617, 26785.732142678324, 33230.667693323725, 40233.0, 40368.596314036484, 40523.784286471455, 40591.1881762369, 401743.0, 403142.0, 403222.0, 404425.0, 404718.0, 21604.89197554002, 21867.45331366665, 21924.780752192273, 21946.012429440394, 22022.0, 37694.246115078095, 38026.0493487665, 38305.0, 38463.615363846, 38596.0, 31650.366992659558, 31996.0, 32261.35477290488, 32897.83551082229, 33413.83293083519, 32246.83876580602, 32844.0, 32881.506777398834, 33052.52106092101, 33865.66134338625, 31410.0, 31834.522482162323, 32178.83910580432, 32558.67441325651, 32602.0, 27858.804268615004, 28820.855895721226, 28926.710732893243, 29021.85489072541, 29352.706472935, 17830.643387132444, 19604.921729305024, 21165.89417052905, 22828.086876524754, 22946.885265573565], "value": 17489.84378430791, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.57294252196186, 383.19528989122864, 386.96877731616524, 395.1800014968939, 425.59993606010346, 13632.182069075863, 13707.0, 17700.91149544252, 18744.906275468602, 18908.338208162717, 15100.0, 20770.5292423791, 21036.789632103675, 21520.784792152073, 22116.33650990469, 6316.336784637616, 6366.936330636692, 6562.803115906507, 6620.768273110442, 6645.867082658344, 6540.509461790361, 6549.803505894831, 6610.900836487456, 6620.470362371023, 6656.800295991104, 5664.490195882369, 6419.325970773071, 6524.282328943837, 6557.213134423876, 6642.800715978528, 6272.968635156841, 6372.808815735535, 6497.415232629062, 6568.934310656904, 6570.868582628321, 6301.369863013708, 6331.113644089831, 6345.619262844244, 6347.555671103025, 6353.96823015882, 25792.48415031689, 25861.224163275132, 26013.609795852888, 26231.868840655676, 26349.73650263492, 2532.341591186305, 2817.8309301441977, 2828.123281782654, 2835.4045650413436, 2844.416894536615, 12453.315067671274, 15850.0, 16026.0, 16031.19844007795, 16671.33314667412, 1883.3690713610956, 1904.1716853168916, 1941.7572803399576, 1943.6598595245775, 1971.9605607887906, 117964.0, 120671.0, 123570.0, 124134.13798793025, 124473.0, 17159.399421020204, 20280.0, 20984.2720446341, 21138.365849024554, 21220.25729099474, 28848.42303153967, 34660.0140983785, 34838.477422838674, 35048.64951350505, 35074.24628768602, 9296.8605470918, 9375.06249375057, 9819.705408837746, 10017.749556261151, 10201.0, 8114.837703245902, 8124.309433698202, 8273.958630206811, 8296.792580185544, 8298.792530186673, 10176.694699159038, 10481.3187142837, 10521.368717876952, 10610.20423468243, 10618.787624247472, 3489.0753950203216, 4089.4683691119963, 4121.97939010309, 4136.414740787008, 4143.751374917515, 1042.5048102151418, 1068.9572417103384, 1072.9088027517591, 1078.829470025016, 1117.1118960426481, 22266.99798509071, 22785.202517912126, 22839.657405138936, 22860.771392285864, 22901.427464313194, 1087.9292845965144, 1089.1667874076338, 1092.7104317355906, 1128.142611615162, 1132.4620805117668, 11606.76786464266, 11866.88133118675, 11998.220115692453, 12050.638480845588, 12144.0, 666543.0, 676711.0, 697909.0, 699242.0, 705634.0, 126471.0, 130764.0, 153364.0, 160870.0, 165068.0, 801.997503121087, 1036.0520124086377, 1072.6966735416506, 1080.3517889266377, 1089.8147314956477, 14932.552023439313, 18623.90688046551, 19152.233910643976, 19821.504462388555, 19966.800331997074, 48089.75955120202, 53955.0, 62879.6856015717, 63759.681201593696, 63829.36170638234, 83199.75200371878, 86249.0, 89308.0, 90591.0, 91859.54070229606, 9377.531123443938, 10676.160137408853, 11225.4387280634, 11595.652130436101, 11696.239744416585, 11552.884471155181, 12423.44094515767, 12596.433160507982, 12705.745885082431, 12755.298458584495, 9385.63908233316, 10730.517126729472, 10882.455877206266, 11240.55037798479, 11452.3701196435, 8493.0, 10297.588096476356, 10932.289401188897, 11029.172812038969, 11190.720231994264, 10963.945180274366, 11057.170712196457, 11079.781224065508, 11086.334819910831, 11474.0, 11751.588694395656, 11960.64118076459, 12060.276383417024, 12599.118061735591, 12938.935305323414, 9819.45992970365, 10354.378737275787, 10724.873888241935, 11624.94187529057, 11663.650090497298], "value": 11214.930652773997, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1365.8868022561612, 1530.3343045775089, 1551.0538571471407, 1571.8349573294806, 1586.9605408457458, 10305.948470257648, 10492.68521944341, 10758.246922715412, 10932.0, 10946.781064378718, 10645.041946224857, 10859.511321990509, 10861.6741497755, 10891.945540272307, 10896.128309735223, 2303.7696230376955, 2352.2943117064897, 2355.1639168095335, 2362.6337917622795, 2367.6566897799808, 2330.720313562375, 2350.330155905567, 2358.5282943411303, 2365.9171928982487, 2366.5858474766933, 2328.37133973827, 2329.4292898239955, 2350.5063936573342, 2354.0, 2355.3287313115766, 2315.976840231593, 2317.9072837086496, 2318.8376813623136, 2337.3338598499518, 2337.812974961999, 2298.7931086202157, 2343.367290831466, 2360.362702070431, 2368.9644655330185, 2370.0282884017643, 10652.467376631137, 10664.360138391721, 10745.355278683304, 10749.946250268777, 10750.086242669382, 5491.450854914517, 5632.436756324376, 5778.0, 5782.0, 5809.593328467009, 3298.208429976812, 3492.6158122606494, 3563.4832949222478, 3576.998440436682, 3640.9259268515852, 1108.661858133269, 1182.9408529573573, 1207.0584943743806, 1228.1280290993404, 1245.785359274709, 12975.416106275146, 13158.868411315956, 13299.26854023038, 14182.787258191136, 14383.35274912621, 4706.10583989041, 4758.881027974328, 4817.662763606514, 4889.3888263967, 4892.339534162914, 12569.0, 12604.432800523999, 12638.55765048219, 12644.557440489538, 12662.873371266354, 5806.506446952057, 5865.765369385262, 5905.409459054146, 5922.378150294243, 5929.673867937305, 5116.360454943132, 5200.479952004769, 5215.400228973641, 5215.817446389358, 5225.190095535198, 3359.152466143611, 3431.9828400857837, 3644.4715516249994, 3838.8272527736317, 4061.4923134608175, 1115.6039605939936, 1137.732632831284, 1139.128566646516, 1149.683836944847, 1152.193464574801, 671.8679025841473, 760.9809754756176, 898.6585097662846, 915.0803442540205, 928.6935311347239, 5883.294004719461, 6179.814605561751, 6324.083007963912, 6405.910995130839, 6506.772262970773, 923.8337099322187, 1005.3264312910368, 1027.1423361493087, 1032.5301987595626, 1039.4594810698395, 4061.903286112733, 4070.9592904071174, 4090.9181816363507, 4164.250434921682, 4235.78821058952, 11065.889341106647, 12796.872031279754, 13198.868011319955, 13228.14017088886, 13262.403191856389, 12642.115051946459, 12876.420561074774, 13123.934380328037, 13168.934155329162, 13181.340932953313, 9822.214222862154, 9846.60613575449, 9856.408615482951, 9969.351992120633, 9995.450250236307, 5086.364204474441, 6133.50931925436, 6189.226346706661, 6332.3034466207755, 6405.807825765234, 10770.676879693621, 11915.106367022681, 11939.343336116597, 11982.940085299866, 12117.394130293625, 11724.70688232801, 11914.463849126983, 12015.639530814089, 12082.154249202824, 12125.878741212828, 8451.704190353308, 8847.646094156162, 8881.555922203994, 9065.954670226607, 9077.954610226907, 9820.901790982, 9978.950105249427, 10037.247206459399, 10047.698569042941, 10072.798544029225, 8447.915520844714, 8811.0, 8834.249088827522, 8869.290456763314, 8958.776030599287, 8520.957395212985, 8574.742757717278, 8581.528015959182, 8583.65665373378, 8729.51987640686, 7518.473706840468, 7613.885791713018, 7644.579548124684, 7649.082110146818, 7663.501872378278, 5616.915746263891, 5639.379668236409, 5714.514266287246, 5814.127880817911, 5816.127580862735, 5658.20785090096, 5727.4845263926445, 5900.616459930091, 5935.673537955454, 5988.820335389945], "value": 5024.136132469668, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.8123126124325, 313.75840602735894, 351.94896739972705, 362.72251727428505, 370.60530534980245, 5032.421271553763, 5376.182910133804, 5495.598622351305, 5709.257796486461, 5739.368669446358, 4583.977080114603, 5320.733963301829, 5440.836774896759, 5531.861703457415, 5618.803341883025, 4863.343448634424, 4864.635152363569, 4875.440393793269, 4880.999395123991, 4882.292067650189, 4320.925955541342, 4631.9536804632025, 4669.322948172513, 4872.78072486739, 4974.527419895104, 4547.886302842422, 4613.584777370036, 4626.398568186131, 4643.605293550052, 4652.906192213501, 4423.51341352451, 4623.283391074388, 4685.812567497296, 4689.155951928668, 4720.221163508017, 4127.752334859918, 4192.798780640158, 4241.172971270604, 4313.619641714653, 4313.921519620095, 5215.608829337777, 5415.241866138744, 5485.270016388854, 5488.0, 5507.339119305709, 5357.901630165807, 5373.194020896858, 5390.1375779875425, 5400.540954018913, 5401.567874570025, 5119.974400128012, 5670.291213598301, 6012.248468941382, 6051.546134039964, 6085.330613632497, 4713.220068358136, 4725.692829966041, 4751.71489710615, 4755.405574303212, 4796.0, 5056.418511871145, 6341.714622841937, 6351.523635727339, 6380.393862583047, 6380.425761681471, 4333.090051089243, 4381.364702118175, 4822.493638168012, 4842.225243960949, 4919.508049195052, 4506.355180359145, 4538.364628951949, 4716.764161791965, 4923.532264434873, 4989.376327959005, 4795.328653988445, 4905.779239934212, 5283.7054921543695, 5345.812896548602, 5407.945920540744, 5006.148954677714, 5208.8958220835375, 5608.887822243614, 5939.316978547436, 5969.970150149222, 1361.1696864912396, 1570.9396157593808, 1613.3546581367311, 1621.1894052973514, 1621.9756703649455, 1123.8651361836633, 1146.1575741829915, 1158.3397463445924, 1207.076586411396, 1231.4027696567157, 5191.118928994444, 5197.7401129943355, 5198.622365073259, 5216.217567364925, 5386.91919621206, 5063.822556300826, 5432.859099589131, 5536.640118392292, 5766.702491939364, 5880.970595146997, 4394.890127746768, 4591.77041147948, 4833.679833679854, 4932.482089380635, 5311.548518375904, 2825.5055365310986, 3048.4055609156444, 3073.602589979819, 3077.569140320357, 3170.5878235829196, 5423.484768946943, 5602.198900549725, 5991.850203744942, 6129.478994285447, 6141.048137538689, 5185.844424667266, 5585.58108141891, 5674.333063460167, 5852.356240813507, 5963.823204530315, 5102.591792656579, 5200.6619569727845, 5539.842289894721, 5753.1945527627, 5997.970010149921, 4488.169688607618, 5040.6138311964505, 5172.456892026434, 5305.973470132779, 5539.639923404966, 5087.465816089258, 5171.81898633546, 5416.350037995308, 5509.00837849191, 5711.314642242957, 5300.893982120258, 5391.973040134774, 5737.225474561048, 6056.636601803906, 6208.9689551551955, 3976.547814992575, 4174.998000479803, 4269.530351661253, 4553.931691024571, 4616.769161541843, 5200.739963001759, 5321.574274057989, 5853.868180523399, 5879.412058794086, 6012.158297838394, 4884.511548845087, 5162.741862906764, 5660.462256085665, 5855.736491857791, 5985.396352586638, 4936.531029552187, 5913.822585322448, 5928.399332180201, 5940.1683764272175, 5992.940070599413, 5036.395632524121, 5556.416576259596, 5560.0, 5703.942960570341, 5729.8567535811935, 5725.255716756795, 5981.312149102909, 5984.341722410444, 6007.626987340063, 6024.608400454132, 5500.0, 5574.637648552831, 5682.6306290091015, 5699.344575373885, 5881.705914704162], "value": 4441.126007694825, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.0160403780195, 687.8968154776786, 764.2281295891152, 807.5074204735113, 834.4200780457583, 14909.701805963889, 15527.146006969611, 15536.300866461008, 15833.287502062407, 16599.75501837361, 16549.503514894543, 16888.662226755456, 16894.831051689478, 16934.661306773858, 17016.659666806656, 835.7994081420446, 937.4281688170211, 953.8569214617828, 961.0341606685272, 962.5668449197854, 746.231381676871, 950.2682934140742, 952.2619969523605, 956.6795123633591, 960.6493629825134, 1004.5178314409088, 1004.9547770350315, 1007.7631756537207, 1009.1523120578712, 1010.8685870836817, 881.2817553693783, 994.2841154368842, 995.6714284286204, 996.4021587047786, 1005.6178652112187, 742.6064185981438, 743.6393349225622, 743.7322563877, 744.151667099507, 744.1814004594956, 11477.942610286895, 15340.463083792014, 15720.21398930049, 15816.130112843788, 16318.347266109342, 13908.58274251764, 14180.716385672229, 14187.78718319226, 14257.78613320801, 14288.357023933948, 11437.485313160927, 11534.826977595343, 11623.418829058597, 11741.88258117425, 12051.276923384625, 3595.1731101846462, 3626.909327266813, 3638.126849556114, 3707.0917625181733, 3716.591174970751, 98478.5076074615, 102987.45518817224, 104515.4774226124, 105128.94871051192, 105235.0, 8494.74515764528, 9314.835645544306, 10499.317544359745, 10563.20775941792, 10770.730731731614, 21482.033308501155, 21565.67651485229, 21898.89050554737, 22021.449463763533, 22103.889480552494, 7400.5189662671755, 7634.198409166957, 7661.0, 7712.691492340356, 7748.961255193688, 4067.877963661095, 4142.8599868606625, 4238.576142385737, 4337.1976184405985, 4357.782110894443, 3356.647552007004, 3415.0608582639975, 3419.0, 3454.7408944329277, 3455.4989526519016, 543.8232574413331, 625.587112505744, 632.5129650169343, 669.2003056347618, 695.0581961442274, 639.6865535887474, 652.6475703120262, 666.1007639686482, 692.4529621598881, 698.26333218454, 9435.669751558662, 9815.329626687875, 9856.753581160385, 10005.14956228714, 10215.846762298572, 629.8536663272891, 677.5866721300004, 686.4165459359505, 697.435077587156, 702.9367356937798, 4641.675082744176, 4774.212254977959, 4776.283557466339, 4831.855044348676, 4836.46798852126, 79580.60209698914, 100445.0, 103212.0, 104241.0, 105369.94630053602, 64468.0, 72053.639731801, 73461.0, 75749.72750817484, 89040.0, 60060.0, 72953.90569141466, 73032.0, 73299.0, 74115.62942185362, 9705.398534469698, 10135.923491927173, 10344.194539130644, 10495.527701253615, 10526.789464210826, 36372.27255454929, 48158.5184148154, 48314.75842620905, 48641.75679121582, 48879.26681099715, 60262.39737602568, 61933.69033154805, 64896.67551662211, 65762.67118664568, 68421.0, 12462.626121216377, 12613.74772504563, 12631.557895473614, 12675.80986285188, 13171.538996135088, 11436.428178590872, 14206.715865682421, 14306.0, 14436.422543098579, 14699.706005879609, 12307.138500305251, 13291.003174762125, 13346.265955372548, 13358.799618005543, 13635.65910852197, 12920.676983075498, 13225.537106201622, 13253.536126235536, 13600.795988060385, 13784.517541885985, 12843.458784945493, 12917.935410323264, 12927.676808079497, 13209.339533023118, 13399.866001340251, 9420.905790942003, 10657.893421066, 10670.253082284165, 10777.407242601732, 11427.257228280136, 9751.804963900824, 10440.776418983978, 10626.681199564026, 10702.411367374869, 10709.946450267698], "value": 7461.821451210218, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1606.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 119.0, 140.0, 120.0, 119.0, 118.0, 117.0, 119.0, 118.0, 120.0], "value": 119.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 145.0, 143.0, 147.0, 113.0, 113.0, 113.0, 113.0, 133.0, 114.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 205.0, 214.0, 224.0, 196.0, 198.0, 196.0, 213.0, 193.0, 231.0], "value": 207.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 60.0, 60.0, 86.0, 62.0, 60.0, 60.0, 77.0, 100.0, 81.0], "value": 73.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 100.0, 72.0, 97.0, 103.0, 101.0, 91.0, 70.0, 71.0, 85.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 90.0, 90.0, 124.0, 86.0, 135.0, 84.0, 94.0, 134.0, 77.0], "value": 102.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 124.0, 98.0, 99.0, 99.0, 96.0, 99.0, 139.0, 122.0, 151.0], "value": 112.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 144.0, 151.0, 92.0, 89.0, 142.0, 147.0, 95.0, 92.0, 91.0], "value": 114.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 81.0, 101.0, 99.0, 100.0, 72.0, 67.0, 67.0, 95.0], "value": 81.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 64.0, 59.0, 71.0, 66.0, 64.0, 58.0, 57.0, 57.0, 59.0], "value": 61.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 89.0, 87.0, 88.0, 89.0, 91.0, 90.0, 105.0, 107.0], "value": 92.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 143.0, 140.0, 143.0, 141.0, 143.0, 130.0, 153.0, 206.0, 160.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 234.0, 179.0, 228.0, 163.0, 165.0, 202.0, 232.0, 195.0, 177.0], "value": 194.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 69.0, 69.0, 73.0, 79.0, 92.0, 91.0, 75.0, 68.0, 69.0], "value": 75.8, "unit": "score"}]}]} 07:45:24 INFO - Return code: 0 07:45:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:45:24 ERROR - # TBPL SUCCESS # 07:45:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:45:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:45:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:45:24 INFO - 2017-03-01 07:45:24.896 screenresolution[1664:10732] starting screenresolution argv=screenresolution get 07:45:24 INFO - 2017-03-01 07:45:24.899 screenresolution[1664:10732] Display 0: 1600x1200x32@60 07:45:24 INFO - 2017-03-01 07:45:24.905 screenresolution[1665:10735] starting screenresolution argv=screenresolution list 07:45:24 INFO - Available Modes on Display 0 07:45:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:45:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:45:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:45:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:45:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:45:25 INFO - Intel Iris: 07:45:25 INFO - Chipset Model: Intel Iris 07:45:25 INFO - Type: GPU 07:45:25 INFO - Bus: Built-In 07:45:25 INFO - VRAM (Dynamic, Max): 1536 MB 07:45:25 INFO - Vendor: Intel (0x8086) 07:45:25 INFO - Device ID: 0x0a2e 07:45:25 INFO - Revision ID: 0x0009 07:45:25 INFO - Displays: 07:45:25 INFO - Display: 07:45:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:45:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:45:25 INFO - Display Serial Number: 1600x1200 60 07:45:25 INFO - Main Display: Yes 07:45:25 INFO - Mirror: Off 07:45:25 INFO - Online: Yes 07:45:25 INFO - Rotation: Supported 07:45:25 INFO - Return code: 0 07:45:25 INFO - Running post-action listener: _resource_record_post_action 07:45:25 INFO - [mozharness: 2017-03-01 15:45:25.151188Z] Finished run-tests step (success) 07:45:25 INFO - Running post-run listener: _resource_record_post_run 07:45:25 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 38853120; Write bytes: 513747968; Read time: 242; Write time: 1628 07:45:25 INFO - TinderboxPrint: CPU usage
54.5% 07:45:25 INFO - TinderboxPrint: I/O read bytes / time
38,853,120 / 242 07:45:25 INFO - TinderboxPrint: I/O write bytes / time
513,747,968 / 1,628 07:45:25 INFO - TinderboxPrint: CPU idle
2,188.8 (45.5%) 07:45:25 INFO - TinderboxPrint: CPU system
960.9 (20.0%) 07:45:25 INFO - TinderboxPrint: CPU user
1,660.0 (34.5%) 07:45:25 INFO - TinderboxPrint: Swap in / out
249,761,792 / 0 07:45:25 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203069952; Write bytes: 203212288; Read time: 15551; Write time: 453 07:45:25 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 37898752; Write bytes: 300729856; Read time: 230; Write time: 1140 07:45:25 INFO - Running post-run listener: _upload_blobber_files 07:45:25 INFO - Blob upload gear active. 07:45:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:45:25 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:45:25 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:45:25 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:45:26 INFO - (blobuploader) - INFO - Open directory for files ... 07:45:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:45:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:45:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:45:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:45:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:45:27 INFO - (blobuploader) - INFO - Done attempting. 07:45:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:45:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:45:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:45:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:45:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:45:28 INFO - (blobuploader) - INFO - Done attempting. 07:45:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:45:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:45:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:45:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:45:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:45:29 INFO - (blobuploader) - INFO - Done attempting. 07:45:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:45:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:45:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:45:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:45:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:45:31 INFO - (blobuploader) - INFO - Done attempting. 07:45:31 INFO - (blobuploader) - INFO - Iteration through files over. 07:45:31 INFO - Return code: 0 07:45:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:45:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:45:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dba869df3d0890c421dbec7c34e76773a7c3aa88626711a010a22a231e0630849754f292faf518c6e3db5830786692926c5e06cb41e0154afb9d6f619ef8e328", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6599740ce2347aadf0363515fe580e9808b34beb99111cca5f113aebcee027959b910fb172f48252116ff0a191b25817ffd373aa4ec8cce2d6f64a8e243fff9e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/172b1d912a132dcf1d6e7e7087793cda280d40a9d803d66d5a2499fd4947ee24b3dc5e5e23dc997a9203f600442d33d6503643eb8edfb69a1dfec3610c00af9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93e662e4bfff2da26189b70e6fe7872cf2c864f86ca22f808cb0d273bda1eb695a4bb4a28706dfa1de2999ea44cd1206c06f1461448291d1a19eb5798260e24e"} 07:45:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:45:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:45:31 INFO - Contents: 07:45:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dba869df3d0890c421dbec7c34e76773a7c3aa88626711a010a22a231e0630849754f292faf518c6e3db5830786692926c5e06cb41e0154afb9d6f619ef8e328", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6599740ce2347aadf0363515fe580e9808b34beb99111cca5f113aebcee027959b910fb172f48252116ff0a191b25817ffd373aa4ec8cce2d6f64a8e243fff9e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/172b1d912a132dcf1d6e7e7087793cda280d40a9d803d66d5a2499fd4947ee24b3dc5e5e23dc997a9203f600442d33d6503643eb8edfb69a1dfec3610c00af9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93e662e4bfff2da26189b70e6fe7872cf2c864f86ca22f808cb0d273bda1eb695a4bb4a28706dfa1de2999ea44cd1206c06f1461448291d1a19eb5798260e24e"} 07:45:31 INFO - Running post-run listener: copy_logs_to_upload_dir 07:45:31 INFO - Copying logs to upload dir... 07:45:31 INFO - mkdir: /builds/slave/test/build/upload/logs 07:45:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.296718 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-01 07:45:31.610476) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-01 07:45:31.613996) ========= 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.QRA5LIuqlW/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.rVEUYRDJs5/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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/dba869df3d0890c421dbec7c34e76773a7c3aa88626711a010a22a231e0630849754f292faf518c6e3db5830786692926c5e06cb41e0154afb9d6f619ef8e328", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6599740ce2347aadf0363515fe580e9808b34beb99111cca5f113aebcee027959b910fb172f48252116ff0a191b25817ffd373aa4ec8cce2d6f64a8e243fff9e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/172b1d912a132dcf1d6e7e7087793cda280d40a9d803d66d5a2499fd4947ee24b3dc5e5e23dc997a9203f600442d33d6503643eb8edfb69a1dfec3610c00af9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93e662e4bfff2da26189b70e6fe7872cf2c864f86ca22f808cb0d273bda1eb695a4bb4a28706dfa1de2999ea44cd1206c06f1461448291d1a19eb5798260e24e"} 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.011030 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/dba869df3d0890c421dbec7c34e76773a7c3aa88626711a010a22a231e0630849754f292faf518c6e3db5830786692926c5e06cb41e0154afb9d6f619ef8e328", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6599740ce2347aadf0363515fe580e9808b34beb99111cca5f113aebcee027959b910fb172f48252116ff0a191b25817ffd373aa4ec8cce2d6f64a8e243fff9e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/172b1d912a132dcf1d6e7e7087793cda280d40a9d803d66d5a2499fd4947ee24b3dc5e5e23dc997a9203f600442d33d6503643eb8edfb69a1dfec3610c00af9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93e662e4bfff2da26189b70e6fe7872cf2c864f86ca22f808cb0d273bda1eb695a4bb4a28706dfa1de2999ea44cd1206c06f1461448291d1a19eb5798260e24e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-01 07:45:31.655902) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 07:45:31.656539) ========= 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.QRA5LIuqlW/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.rVEUYRDJs5/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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.005030 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 07:45:31.707637) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-01 07:45:31.707940) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-01 07:45:31.708297) ========= ========= Total master_lag: 0.21 =========