builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0084 starttime: 1488382376.56 results: success (0) buildid: 20170301072336 builduid: 7c30fda4e6d84bbdafbc45cd8658a9b4 revision: 34c6c2f302e7b48e3ad2cec575cbd34d423a9d32 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-01 07:32:56.559888) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-01 07:32:56.560565) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-01 07:32:56.561026) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-01 07:32:56.589272) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 07:32:56.589547) ========= 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.fenjGYHEa8/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.JQggOq62of/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:32:56-- 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% 187M=0s 2017-03-01 07:32:56 (187 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122368 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 07:32:56.737871) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 07:32:56.738242) ========= 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.fenjGYHEa8/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.JQggOq62of/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.027894 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 07:32:56.787509) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-01 07:32:56.787855) ========= 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.fenjGYHEa8/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.JQggOq62of/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:32:56,890 truncating revision to first 12 chars 2017-03-01 07:32:56,890 Setting DEBUG logging. 2017-03-01 07:32:56,890 attempt 1/10 2017-03-01 07:32:56,890 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:32:58,084 unpacking tar archive at: mozilla-central-34c6c2f302e7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.458746 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-01 07:32:58.271862) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-01 07:32:58.272176) ========= script_repo_revision: 34c6c2f302e7b48e3ad2cec575cbd34d423a9d32 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-01 07:32:58.272554) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-01 07:32:58.272825) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-01 07:32:58.293208) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-01 07:32:58.293532) ========= /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.fenjGYHEa8/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.JQggOq62of/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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:32:58 INFO - MultiFileLogger online at 20170301 07:32:58 in /builds/slave/test 07:32:58 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:32:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:32:58 INFO - {'append_to_log': False, 07:32:58 INFO - 'base_work_dir': '/builds/slave/test', 07:32:58 INFO - 'blob_upload_branch': 'Firefox', 07:32:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:32:58 INFO - 'branch': 'Firefox', 07:32:58 INFO - 'buildbot_json_path': 'buildprops.json', 07:32:58 INFO - 'config_files': ('talos/mac_config.py',), 07:32:58 INFO - 'default_actions': ('clobber', 07:32:58 INFO - 'read-buildbot-config', 07:32:58 INFO - 'download-and-extract', 07:32:58 INFO - 'populate-webroot', 07:32:58 INFO - 'create-virtualenv', 07:32:58 INFO - 'install', 07:32:58 INFO - 'run-tests'), 07:32:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:32:58 INFO - 'download_minidump_stackwalk': True, 07:32:58 INFO - 'download_symbols': 'ondemand', 07:32:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:32:58 INFO - 'tooltool.py': '/tools/tooltool.py', 07:32:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:32:58 INFO - '/tools/misc-python/virtualenv.py')}, 07:32:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:32:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:32:58 INFO - 'gecko_profile': False, 07:32:58 INFO - 'gecko_profile_interval': 0, 07:32:58 INFO - 'installer_path': 'installer.exe', 07:32:58 INFO - 'log_level': 'info', 07:32:58 INFO - 'log_name': 'talos', 07:32:58 INFO - 'log_to_console': True, 07:32:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:32:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:32:58 INFO - 'opt_config_files': (), 07:32:58 INFO - 'pip_index': False, 07:32:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:32:58 INFO - 'cmd': ('bash', 07:32:58 INFO - '-c', 07:32:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:32:58 INFO - 'enabled': True, 07:32:58 INFO - 'halt_on_failure': False, 07:32:58 INFO - 'name': 'check_screen_resolution'},), 07:32:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:32:58 INFO - 'cmd': ('bash', 07:32:58 INFO - '-c', 07:32:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:32:58 INFO - 'enabled': True, 07:32:58 INFO - 'halt_on_failure': False, 07:32:58 INFO - 'name': 'check_screen_resolution'},), 07:32:58 INFO - 'run_cmd_checks_enabled': True, 07:32:58 INFO - 'suite': 'dromaeojs-e10s', 07:32:58 INFO - 'system_bits': '32', 07:32:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:32:58 INFO - 'title': 't-yosemite-r7-0084', 07:32:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:32:58 INFO - 'use_talos_json': True, 07:32:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:32:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:32:58 INFO - 'work_dir': 'build'} 07:32:58 INFO - [mozharness: 2017-03-01 15:32:58.508215Z] Running clobber step. 07:32:58 INFO - Running pre-action listener: _resource_record_pre_action 07:32:58 INFO - Running main action method: clobber 07:32:58 INFO - rmtree: /builds/slave/test/build 07:32:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:33:00 INFO - Running post-action listener: _resource_record_post_action 07:33:00 INFO - [mozharness: 2017-03-01 15:33:00.982861Z] Finished clobber step (success) 07:33:00 INFO - [mozharness: 2017-03-01 15:33:00.982991Z] Running read-buildbot-config step. 07:33:00 INFO - Running pre-action listener: _resource_record_pre_action 07:33:00 INFO - Running main action method: read_buildbot_config 07:33:00 INFO - Using buildbot properties: 07:33:00 INFO - { 07:33:00 INFO - "project": "", 07:33:00 INFO - "product": "firefox", 07:33:00 INFO - "script_repo_revision": "production", 07:33:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:33:00 INFO - "repository": "", 07:33:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:33:00 INFO - "buildid": "20170301072336", 07:33:00 INFO - "stage_platform": "macosx64", 07:33:00 INFO - "basedir": "/builds/slave/test", 07:33:00 INFO - "buildnumber": 5, 07:33:00 INFO - "platform": "yosemite_r7", 07:33:00 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 07:33:00 INFO - "slavebuilddir": "test", 07:33:00 INFO - "branch": "mozilla-central", 07:33:00 INFO - "revision": "34c6c2f302e7b48e3ad2cec575cbd34d423a9d32", 07:33:00 INFO - "slavename": "t-yosemite-r7-0084", 07:33:00 INFO - "builduid": "7c30fda4e6d84bbdafbc45cd8658a9b4", 07:33:00 INFO - "repo_path": "mozilla-central" 07:33:00 INFO - } 07:33:00 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.dmg. 07:33:00 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.test_packages.json. 07:33:00 INFO - Running post-action listener: _resource_record_post_action 07:33:00 INFO - [mozharness: 2017-03-01 15:33:00.986177Z] Finished read-buildbot-config step (success) 07:33:00 INFO - [mozharness: 2017-03-01 15:33:00.986297Z] Running download-and-extract step. 07:33:00 INFO - Running pre-action listener: _resource_record_pre_action 07:33:00 INFO - Running main action method: download_and_extract 07:33:00 INFO - mkdir: /builds/slave/test/build/tests 07:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:00 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.test_packages.json 07:33:00 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 07:33:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 07:33:01 INFO - Downloaded 1199 bytes. 07:33:01 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 07:33:01 INFO - Using the following test package requirements: 07:33:01 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 07:33:01 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:33:01 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 07:33:01 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:33:01 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 07:33:01 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:33:01 INFO - u'jsshell-mac.zip'], 07:33:01 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:33:01 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 07:33:01 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 07:33:01 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:33:01 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 07:33:01 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:33:01 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 07:33:01 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:33:01 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 07:33:01 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:33:01 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 07:33:01 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:33:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.common.tests.zip 07:33:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:33:01 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.common.tests.zip into memory 07:33:02 INFO - Expected file size: 51762826 07:33:02 INFO - Obtained file size: 51762826 07:33:18 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:33:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.common.tests.zip 07:33:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:33:18 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.common.tests.zip into memory 07:33:19 INFO - Expected file size: 51762826 07:33:19 INFO - Obtained file size: 51762826 07:33:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.talos.tests.zip 07:33:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:33:34 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 07:33:35 INFO - Expected file size: 14404421 07:33:35 INFO - Obtained file size: 14404421 07:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:35 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.dmg 07:33:35 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 07:33:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 07:33:37 INFO - Downloaded 74973774 bytes. 07:33:37 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.dmg 07:33:37 INFO - mkdir: /builds/slave/test/properties 07:33:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:33:37 INFO - Writing to file /builds/slave/test/properties/build_url 07:33:37 INFO - Contents: 07:33:37 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1488364923/firefox-54.0a1.en-US.mac.dmg 07:33:37 INFO - Running post-action listener: _resource_record_post_action 07:33:37 INFO - Running post-action listener: set_extra_try_arguments 07:33:37 INFO - [mozharness: 2017-03-01 15:33:37.836734Z] Finished download-and-extract step (success) 07:33:37 INFO - [mozharness: 2017-03-01 15:33:37.836886Z] Running populate-webroot step. 07:33:37 INFO - Running pre-action listener: _resource_record_pre_action 07:33:37 INFO - Running main action method: populate_webroot 07:33:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:33:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:33:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:33:37 INFO - '--tppagecycles', 07:33:37 INFO - '7'], 07:33:37 INFO - 'tests': ['tsvgm']}}, 07:33:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:33:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:33:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:33:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:33:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:33:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:33:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:33:37 INFO - 'talos_options': ['--disable-e10s'], 07:33:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:33:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:33:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:33:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:33:37 INFO - 'talos_options': ['--disable-e10s'], 07:33:37 INFO - 'tests': ['damp', 'tps']}, 07:33:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:33:37 INFO - 'tests': ['damp', 'tps']}, 07:33:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:33:37 INFO - 'tests': ['dromaeo_dom']}, 07:33:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:33:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:33:37 INFO - 'tests': ['basic_compositor_video']}, 07:33:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:33:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:33:37 INFO - 'tests': ['a11yr', 07:33:37 INFO - 'ts_paint', 07:33:37 INFO - 'tpaint', 07:33:37 INFO - 'sessionrestore', 07:33:37 INFO - 'sessionrestore_no_auto_restore', 07:33:37 INFO - 'tabpaint']}, 07:33:37 INFO - 'other-e10s': {'tests': ['a11yr', 07:33:37 INFO - 'ts_paint', 07:33:37 INFO - 'tpaint', 07:33:37 INFO - 'sessionrestore', 07:33:37 INFO - 'sessionrestore_no_auto_restore', 07:33:37 INFO - 'tabpaint']}, 07:33:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:33:37 INFO - 'ts_paint', 07:33:37 INFO - 'tpaint', 07:33:37 INFO - 'sessionrestore', 07:33:37 INFO - 'sessionrestore_no_auto_restore', 07:33:37 INFO - 'tabpaint']}, 07:33:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:33:37 INFO - 'ts_paint', 07:33:37 INFO - 'tpaint', 07:33:37 INFO - 'sessionrestore', 07:33:37 INFO - 'sessionrestore_no_auto_restore', 07:33:37 INFO - 'tabpaint']}, 07:33:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:33:37 INFO - 'tests': ['a11yr', 07:33:37 INFO - 'ts_paint', 07:33:37 INFO - 'tpaint', 07:33:37 INFO - 'sessionrestore', 07:33:37 INFO - 'sessionrestore_no_auto_restore', 07:33:37 INFO - 'tabpaint']}, 07:33:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:33:37 INFO - 'tests': ['a11yr', 07:33:37 INFO - 'ts_paint', 07:33:37 INFO - 'tpaint', 07:33:37 INFO - 'sessionrestore', 07:33:37 INFO - 'sessionrestore_no_auto_restore', 07:33:37 INFO - 'tabpaint']}, 07:33:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:33:37 INFO - 'tests': ['tsvgx', 07:33:37 INFO - 'tsvgr_opacity', 07:33:37 INFO - 'tart', 07:33:37 INFO - 'tscrollx', 07:33:37 INFO - 'cart', 07:33:37 INFO - 'tsvg_static']}, 07:33:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:33:37 INFO - 'tsvgr_opacity', 07:33:37 INFO - 'tart', 07:33:37 INFO - 'tscrollx', 07:33:37 INFO - 'cart', 07:33:37 INFO - 'tsvg_static']}, 07:33:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:33:37 INFO - 'talos_options': ['--disable-e10s'], 07:33:37 INFO - 'tests': ['tp5o']}, 07:33:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:33:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:33:37 INFO - 'talos_options': ['--disable-e10s', 07:33:37 INFO - '--xperf_path', 07:33:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:33:37 INFO - 'tests': ['tp5n']}, 07:33:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:33:37 INFO - 'talos_options': ['--xperf_path', 07:33:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:33:37 INFO - 'tests': ['tp5n']}}, 07:33:37 INFO - 'talos.zip': {'path': '', 07:33:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:33:37 INFO - Running post-action listener: _resource_record_post_action 07:33:37 INFO - [mozharness: 2017-03-01 15:33:37.855354Z] Finished populate-webroot step (success) 07:33:37 INFO - [mozharness: 2017-03-01 15:33:37.855457Z] Running create-virtualenv step. 07:33:37 INFO - Running pre-action listener: _resource_record_pre_action 07:33:37 INFO - Running main action method: create_virtualenv 07:33:37 INFO - Creating virtualenv /builds/slave/test/build/venv 07:33:37 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:33:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:33:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:33:37 INFO - Using real prefix '/tools/python27' 07:33:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:33:38 INFO - Installing distribute.............................................................................................................................................................................................done. 07:33:41 INFO - Installing pip.................done. 07:33:41 INFO - Return code: 0 07:33:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:33:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:33:41 INFO - Reading from file tmpfile_stdout 07:33:41 INFO - Output received: 07:33:41 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:33:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:33: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:33:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33: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:33:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:41 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 0x10aaf0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e0c86c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ced30>, '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 0x10a7f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3be8>, '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.fenjGYHEa8/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.JQggOq62of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:33:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:33:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:33:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fenjGYHEa8/Render', 07:33:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:41 INFO - 'HOME': '/Users/cltbld', 07:33:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:41 INFO - 'LOGNAME': 'cltbld', 07:33:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:41 INFO - 'MOZ_NO_REMOTE': '1', 07:33:41 INFO - 'NO_EM_RESTART': '1', 07:33:41 INFO - 'PAGER': '/bin/cat', 07:33:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:41 INFO - 'PWD': '/builds/slave/test', 07:33:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:41 INFO - 'SHELL': '/bin/bash', 07:33:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JQggOq62of/Listeners', 07:33:41 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:33:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:41 INFO - 'USER': 'cltbld', 07:33:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:41 INFO - 'XPC_FLAGS': '0x0', 07:33:41 INFO - 'XPC_SERVICE_NAME': '0', 07:33:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:33:42 INFO - Cleaning up... 07:33:42 INFO - Return code: 0 07:33:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:33:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10aaf0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e0c86c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ced30>, '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 0x10a7f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3be8>, '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.fenjGYHEa8/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.JQggOq62of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:33:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:33:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:33:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fenjGYHEa8/Render', 07:33:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:42 INFO - 'HOME': '/Users/cltbld', 07:33:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:42 INFO - 'LOGNAME': 'cltbld', 07:33:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:42 INFO - 'MOZ_NO_REMOTE': '1', 07:33:42 INFO - 'NO_EM_RESTART': '1', 07:33:42 INFO - 'PAGER': '/bin/cat', 07:33:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:42 INFO - 'PWD': '/builds/slave/test', 07:33:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:42 INFO - 'SHELL': '/bin/bash', 07:33:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JQggOq62of/Listeners', 07:33:42 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:33:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:42 INFO - 'USER': 'cltbld', 07:33:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:42 INFO - 'XPC_FLAGS': '0x0', 07:33:42 INFO - 'XPC_SERVICE_NAME': '0', 07:33:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:42 INFO - Downloading/unpacking psutil>=3.1.1 07:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:33:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:33:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:33:45 INFO - Installing collected packages: psutil 07:33:45 INFO - Running setup.py install for psutil 07:33:46 INFO - building 'psutil._psutil_osx' extension 07:33:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:33:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:33:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:33:46 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:33:46 INFO - building 'psutil._psutil_posix' extension 07:33:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:33:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:33:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:33:46 INFO - ^ 07:33:46 INFO - 1 warning generated. 07:33:46 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:33:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:33:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:33:47 INFO - Successfully installed psutil 07:33:47 INFO - Cleaning up... 07:33:47 INFO - Return code: 0 07:33:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:47 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 0x10aaf0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e0c86c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ced30>, '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 0x10a7f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3be8>, '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.fenjGYHEa8/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.JQggOq62of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:33:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:33:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fenjGYHEa8/Render', 07:33:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:47 INFO - 'HOME': '/Users/cltbld', 07:33:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:47 INFO - 'LOGNAME': 'cltbld', 07:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:47 INFO - 'MOZ_NO_REMOTE': '1', 07:33:47 INFO - 'NO_EM_RESTART': '1', 07:33:47 INFO - 'PAGER': '/bin/cat', 07:33:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:47 INFO - 'PWD': '/builds/slave/test', 07:33:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:47 INFO - 'SHELL': '/bin/bash', 07:33:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JQggOq62of/Listeners', 07:33:47 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:33:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:47 INFO - 'USER': 'cltbld', 07:33:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:47 INFO - 'XPC_FLAGS': '0x0', 07:33:47 INFO - 'XPC_SERVICE_NAME': '0', 07:33:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:33:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:33:50 INFO - Installing collected packages: mozsystemmonitor 07:33:50 INFO - Running setup.py install for mozsystemmonitor 07:33:50 INFO - Successfully installed mozsystemmonitor 07:33:50 INFO - Cleaning up... 07:33:50 INFO - Return code: 0 07:33:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10aaf0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e0c86c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ced30>, '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 0x10a7f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3be8>, '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.fenjGYHEa8/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.JQggOq62of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:33:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:33:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:33:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fenjGYHEa8/Render', 07:33:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:50 INFO - 'HOME': '/Users/cltbld', 07:33:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:50 INFO - 'LOGNAME': 'cltbld', 07:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:50 INFO - 'MOZ_NO_REMOTE': '1', 07:33:50 INFO - 'NO_EM_RESTART': '1', 07:33:50 INFO - 'PAGER': '/bin/cat', 07:33:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:50 INFO - 'PWD': '/builds/slave/test', 07:33:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:50 INFO - 'SHELL': '/bin/bash', 07:33:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JQggOq62of/Listeners', 07:33:50 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:33:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:50 INFO - 'USER': 'cltbld', 07:33:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:50 INFO - 'XPC_FLAGS': '0x0', 07:33:50 INFO - 'XPC_SERVICE_NAME': '0', 07:33:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:50 INFO - Downloading/unpacking jsonschema==2.5.1 07:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:33:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:53 INFO - Downloading functools32-3.2.3-2.tar.gz 07:33:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:33:53 INFO - warning: no files found matching '*.txt' 07:33:53 INFO - no previously-included directories found matching 'build' 07:33:53 INFO - no previously-included directories found matching 'dist' 07:33:53 INFO - no previously-included directories found matching '.git*' 07:33:53 INFO - Installing collected packages: jsonschema, functools32 07:33:53 INFO - Running setup.py install for functools32 07:33:53 INFO - warning: no files found matching '*.txt' 07:33:53 INFO - no previously-included directories found matching 'build' 07:33:53 INFO - no previously-included directories found matching 'dist' 07:33:53 INFO - no previously-included directories found matching '.git*' 07:33:54 INFO - Successfully installed jsonschema functools32 07:33:54 INFO - Cleaning up... 07:33:54 INFO - Return code: 0 07:33:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:33:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:54 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 0x10aaf0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e0c86c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ced30>, '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 0x10a7f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3be8>, '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.fenjGYHEa8/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.JQggOq62of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:33:54 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:33:54 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:33:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fenjGYHEa8/Render', 07:33:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:54 INFO - 'HOME': '/Users/cltbld', 07:33:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:54 INFO - 'LOGNAME': 'cltbld', 07:33:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:54 INFO - 'MOZ_NO_REMOTE': '1', 07:33:54 INFO - 'NO_EM_RESTART': '1', 07:33:54 INFO - 'PAGER': '/bin/cat', 07:33:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:54 INFO - 'PWD': '/builds/slave/test', 07:33:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:54 INFO - 'SHELL': '/bin/bash', 07:33:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JQggOq62of/Listeners', 07:33:54 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:33:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:54 INFO - 'USER': 'cltbld', 07:33:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:54 INFO - 'XPC_FLAGS': '0x0', 07:33:54 INFO - 'XPC_SERVICE_NAME': '0', 07:33:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:33:55 INFO - Cleaning up... 07:33:55 INFO - Return code: 0 07:33:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:33:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10aaf0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e0c86c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ced30>, '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 0x10a7f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3be8>, '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.fenjGYHEa8/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.JQggOq62of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:33:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:33:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:33:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fenjGYHEa8/Render', 07:33:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:55 INFO - 'HOME': '/Users/cltbld', 07:33:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:55 INFO - 'LOGNAME': 'cltbld', 07:33:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:55 INFO - 'MOZ_NO_REMOTE': '1', 07:33:55 INFO - 'NO_EM_RESTART': '1', 07:33:55 INFO - 'PAGER': '/bin/cat', 07:33:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:55 INFO - 'PWD': '/builds/slave/test', 07:33:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:55 INFO - 'SHELL': '/bin/bash', 07:33:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JQggOq62of/Listeners', 07:33:55 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:33:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:55 INFO - 'USER': 'cltbld', 07:33:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:55 INFO - 'XPC_FLAGS': '0x0', 07:33:55 INFO - 'XPC_SERVICE_NAME': '0', 07:33:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:55 INFO - Downloading/unpacking blobuploader==1.2.4 07:33:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:58 INFO - Downloading blobuploader-1.2.4.tar.gz 07:33:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:33:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:33: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:33: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:33: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:33: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:33: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:33: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:33:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:33:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:33:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:59 INFO - Downloading docopt-0.6.1.tar.gz 07:33:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:34:00 INFO - Installing collected packages: blobuploader, requests, docopt 07:34:00 INFO - Running setup.py install for blobuploader 07:34:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:34:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:34:00 INFO - Running setup.py install for requests 07:34:00 INFO - Running setup.py install for docopt 07:34:00 INFO - Successfully installed blobuploader requests docopt 07:34:00 INFO - Cleaning up... 07:34:00 INFO - Return code: 0 07:34:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x10aaf0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e0c86c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ced30>, '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 0x10a7f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3be8>, '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.fenjGYHEa8/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.JQggOq62of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:34:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:34:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:34:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fenjGYHEa8/Render', 07:34:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:00 INFO - 'HOME': '/Users/cltbld', 07:34:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:00 INFO - 'LOGNAME': 'cltbld', 07:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:00 INFO - 'MOZ_NO_REMOTE': '1', 07:34:00 INFO - 'NO_EM_RESTART': '1', 07:34:00 INFO - 'PAGER': '/bin/cat', 07:34:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:00 INFO - 'PWD': '/builds/slave/test', 07:34:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:00 INFO - 'SHELL': '/bin/bash', 07:34:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JQggOq62of/Listeners', 07:34:00 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:34:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:00 INFO - 'USER': 'cltbld', 07:34:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:00 INFO - 'XPC_FLAGS': '0x0', 07:34:00 INFO - 'XPC_SERVICE_NAME': '0', 07:34:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:34:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ZNeGXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:34:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-OUkzlj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:34:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-cNA6gF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:34:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-S5zKjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:34:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-W6U19j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:34:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-u5PXIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:34:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ewlQ6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:34:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-GkqeEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:34:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-8HChIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:34:02 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-owLDWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:34:02 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-4OPfLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:34:02 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-WdH6mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:34:02 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-1Ws3VE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:34:02 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-tvlAC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:34:02 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-XM8LWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:34:02 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-8d42zi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:34:02 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-DbQcGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:34:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:34:02 INFO - Running setup.py install for manifestparser 07:34:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:34:03 INFO - Running setup.py install for mozcrash 07:34:03 INFO - Running setup.py install for mozdebug 07:34:03 INFO - Running setup.py install for mozdevice 07:34:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:34:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:34:03 INFO - Running setup.py install for mozfile 07:34:03 INFO - Running setup.py install for mozhttpd 07:34:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:34:03 INFO - Running setup.py install for mozinfo 07:34:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:34:03 INFO - Running setup.py install for mozInstall 07:34:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:34:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:34:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:34:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:34:04 INFO - Running setup.py install for mozleak 07:34:04 INFO - Running setup.py install for mozlog 07:34:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:34:04 INFO - Running setup.py install for moznetwork 07:34:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:34:04 INFO - Running setup.py install for mozprocess 07:34:04 INFO - Running setup.py install for mozprofile 07:34:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:34:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:34:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:34:04 INFO - Running setup.py install for mozrunner 07:34:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:34:05 INFO - Running setup.py install for mozscreenshot 07:34:05 INFO - Running setup.py install for moztest 07:34:05 INFO - Running setup.py install for mozversion 07:34:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:34:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:34:05 INFO - Cleaning up... 07:34:05 INFO - Return code: 0 07:34:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:34: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:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34: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:34:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:05 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 0x10aaf0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e0c86c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ced30>, '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 0x10a7f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3be8>, '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.fenjGYHEa8/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.JQggOq62of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:34:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:34:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:34:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fenjGYHEa8/Render', 07:34:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:05 INFO - 'HOME': '/Users/cltbld', 07:34:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:05 INFO - 'LOGNAME': 'cltbld', 07:34:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:05 INFO - 'MOZ_NO_REMOTE': '1', 07:34:05 INFO - 'NO_EM_RESTART': '1', 07:34:05 INFO - 'PAGER': '/bin/cat', 07:34:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:05 INFO - 'PWD': '/builds/slave/test', 07:34:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:05 INFO - 'SHELL': '/bin/bash', 07:34:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JQggOq62of/Listeners', 07:34:05 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:34:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:05 INFO - 'USER': 'cltbld', 07:34:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:05 INFO - 'XPC_FLAGS': '0x0', 07:34:05 INFO - 'XPC_SERVICE_NAME': '0', 07:34:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:34:05 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-0EfazQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:34:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:34:05 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-AaPP9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:34:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:34:05 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-K4TX2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:34:06 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-jg_lSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:34:06 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-nUZ18M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:34:06 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-gRGqBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:34:06 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-E4ZkAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:34:06 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-V_dzsS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:34:06 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Rj0HO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:34:06 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-1vhOcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:34:06 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-iDKTfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:34:06 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-kMT5TF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:34:07 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Efvofn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:34:07 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-8keCMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:34:07 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-m4FyYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:34:07 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-mKdsur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:34:07 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ijAKzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:34:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:34:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:34:10 INFO - Downloading blessings-1.6.tar.gz 07:34:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:34:10 INFO - Installing collected packages: blessings 07:34:10 INFO - Running setup.py install for blessings 07:34:10 INFO - Successfully installed blessings 07:34:10 INFO - Cleaning up... 07:34:10 INFO - Return code: 0 07:34:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:34:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:34:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:34:10 INFO - Reading from file tmpfile_stdout 07:34:10 INFO - Current package versions: 07:34:10 INFO - blessings == 1.6 07:34:10 INFO - blobuploader == 1.2.4 07:34:10 INFO - docopt == 0.6.1 07:34:10 INFO - functools32 == 3.2.3-2 07:34:10 INFO - jsonschema == 2.5.1 07:34:10 INFO - manifestparser == 1.1 07:34:10 INFO - mozInstall == 1.12 07:34:10 INFO - mozcrash == 1.0 07:34:10 INFO - mozdebug == 0.1 07:34:10 INFO - mozdevice == 0.49 07:34:10 INFO - mozfile == 1.2 07:34:10 INFO - mozhttpd == 0.7 07:34:10 INFO - mozinfo == 0.9 07:34:10 INFO - mozleak == 0.1 07:34:10 INFO - mozlog == 3.4 07:34:10 INFO - moznetwork == 0.27 07:34:10 INFO - mozprocess == 0.25 07:34:10 INFO - mozprofile == 0.28 07:34:10 INFO - mozrunner == 6.13 07:34:10 INFO - mozscreenshot == 0.1 07:34:10 INFO - mozsystemmonitor == 0.3 07:34:10 INFO - moztest == 0.8 07:34:10 INFO - mozversion == 1.4 07:34:10 INFO - psutil == 3.1.1 07:34:10 INFO - requests == 1.2.3 07:34:10 INFO - wsgiref == 0.1.2 07:34:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:34:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:10 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 0x10aaf0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e0c86c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ced30>, '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 0x10a7f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3be8>, '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.fenjGYHEa8/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.JQggOq62of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:34:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:34:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:34:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fenjGYHEa8/Render', 07:34:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:10 INFO - 'HOME': '/Users/cltbld', 07:34:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:10 INFO - 'LOGNAME': 'cltbld', 07:34:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:10 INFO - 'MOZ_NO_REMOTE': '1', 07:34:10 INFO - 'NO_EM_RESTART': '1', 07:34:10 INFO - 'PAGER': '/bin/cat', 07:34:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:10 INFO - 'PWD': '/builds/slave/test', 07:34:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:10 INFO - 'SHELL': '/bin/bash', 07:34:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JQggOq62of/Listeners', 07:34:10 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:34:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:10 INFO - 'USER': 'cltbld', 07:34:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:10 INFO - 'XPC_FLAGS': '0x0', 07:34:10 INFO - 'XPC_SERVICE_NAME': '0', 07:34:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:34:11 INFO - Cleaning up... 07:34:11 INFO - Return code: 0 07:34:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:34:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:34:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:34:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--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 0x10aaf0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aaf3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e0c86c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ced30>, '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 0x10a7f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3be8>, '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.fenjGYHEa8/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.JQggOq62of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:34:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:34:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:34:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fenjGYHEa8/Render', 07:34:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:11 INFO - 'HOME': '/Users/cltbld', 07:34:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:11 INFO - 'LOGNAME': 'cltbld', 07:34:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:11 INFO - 'MOZ_NO_REMOTE': '1', 07:34:11 INFO - 'NO_EM_RESTART': '1', 07:34:11 INFO - 'PAGER': '/bin/cat', 07:34:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:11 INFO - 'PWD': '/builds/slave/test', 07:34:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:11 INFO - 'SHELL': '/bin/bash', 07:34:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JQggOq62of/Listeners', 07:34:11 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:34:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:11 INFO - 'USER': 'cltbld', 07:34:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:11 INFO - 'XPC_FLAGS': '0x0', 07:34:11 INFO - 'XPC_SERVICE_NAME': '0', 07:34:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:34:11 INFO - Cleaning up... 07:34:11 INFO - Return code: 0 07:34:11 INFO - Running post-action listener: _resource_record_post_action 07:34:11 INFO - Running post-action listener: _start_resource_monitoring 07:34:11 INFO - Starting resource monitoring. 07:34:11 INFO - [mozharness: 2017-03-01 15:34:11.488060Z] Finished create-virtualenv step (success) 07:34:11 INFO - [mozharness: 2017-03-01 15:34:11.489042Z] Running install step. 07:34:11 INFO - Running pre-action listener: _resource_record_pre_action 07:34:11 INFO - Running main action method: install 07:34:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:34:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:34:11 INFO - Reading from file tmpfile_stdout 07:34:11 INFO - Detecting whether we're running mozinstall >=1.0... 07:34:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:34:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:34:11 INFO - Reading from file tmpfile_stdout 07:34:11 INFO - Output received: 07:34:11 INFO - Usage: mozinstall [options] installer 07:34:11 INFO - Options: 07:34:11 INFO - -h, --help show this help message and exit 07:34:11 INFO - -d DEST, --destination=DEST 07:34:11 INFO - Directory to install application into. [default: 07:34:11 INFO - "/builds/slave/test"] 07:34:11 INFO - --app=APP Application being installed. [default: firefox] 07:34:11 INFO - mkdir: /builds/slave/test/build/application 07:34:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:34:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:34:31 INFO - Reading from file tmpfile_stdout 07:34:31 INFO - Output received: 07:34:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:34:31 INFO - Running post-action listener: _resource_record_post_action 07:34:31 INFO - [mozharness: 2017-03-01 15:34:31.692732Z] Finished install step (success) 07:34:31 INFO - [mozharness: 2017-03-01 15:34:31.692898Z] Running run-tests step. 07:34:31 INFO - Running pre-action listener: _resource_record_pre_action 07:34:31 INFO - Running main action method: run_tests 07:34:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:34:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:34:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:34:31 INFO - 2017-03-01 07:34:31.707 screenresolution[1630:7732] starting screenresolution argv=screenresolution get 07:34:31 INFO - 2017-03-01 07:34:31.710 screenresolution[1630:7732] Display 0: 1600x1200x32@60 07:34:31 INFO - 2017-03-01 07:34:31.716 screenresolution[1631:7735] starting screenresolution argv=screenresolution list 07:34:31 INFO - Available Modes on Display 0 07:34:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:34:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:34:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:34:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:34:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:34:31 INFO - Intel Iris: 07:34:31 INFO - Chipset Model: Intel Iris 07:34:31 INFO - Type: GPU 07:34:31 INFO - Bus: Built-In 07:34:31 INFO - VRAM (Dynamic, Max): 1536 MB 07:34:31 INFO - Vendor: Intel (0x8086) 07:34:31 INFO - Device ID: 0x0a2e 07:34:31 INFO - Revision ID: 0x0009 07:34:31 INFO - Displays: 07:34:31 INFO - Display: 07:34:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:34:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:34:31 INFO - Display Serial Number: 1600x1200 60 07:34:31 INFO - Main Display: Yes 07:34:31 INFO - Mirror: Off 07:34:31 INFO - Online: Yes 07:34:31 INFO - Rotation: Supported 07:34:31 INFO - Return code: 0 07:34:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:34:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:34:31 INFO - Python 2.7.3 07:34:31 INFO - Return code: 0 07:34:31 INFO - grabbing minidump binary from tooltool 07:34:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:31 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 0x10a8ced30>, '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 0x10a7f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:34:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:34:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:34:31 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:34:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:34:32 INFO - Return code: 0 07:34:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:34:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:34:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:34:32 INFO - ENV: RUST_BACKTRACE is now 1 07:34:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:34:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:34:32 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-0084', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:34:32 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-0084 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:34:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fenjGYHEa8/Render', 07:34:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:32 INFO - 'HOME': '/Users/cltbld', 07:34:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:32 INFO - 'LOGNAME': 'cltbld', 07:34:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:34:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:34:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:32 INFO - 'MOZ_NO_REMOTE': '1', 07:34:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:34:32 INFO - 'NO_EM_RESTART': '1', 07:34:32 INFO - 'PAGER': '/bin/cat', 07:34:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:32 INFO - 'PWD': '/builds/slave/test', 07:34:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:34:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:32 INFO - 'RUST_BACKTRACE': '1', 07:34:32 INFO - 'SHELL': '/bin/bash', 07:34:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JQggOq62of/Listeners', 07:34:32 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 07:34:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:32 INFO - 'USER': 'cltbld', 07:34:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:32 INFO - 'XPC_FLAGS': '0x0', 07:34:32 INFO - 'XPC_SERVICE_NAME': '0', 07:34:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:32 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-0084', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:34:32 INFO - mozversion application_buildid: 20170301024203 07:34:32 INFO - mozversion application_changeset: 34c6c2f302e7b48e3ad2cec575cbd34d423a9d32 07:34:32 INFO - mozversion application_display_name: Nightly 07:34:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:34:32 INFO - mozversion application_name: Firefox 07:34:32 INFO - mozversion application_remotingname: firefox 07:34:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:34:32 INFO - mozversion application_vendor: Mozilla 07:34:32 INFO - mozversion application_version: 54.0a1 07:34:32 INFO - mozversion platform_buildid: 20170301024203 07:34:32 INFO - mozversion platform_changeset: 34c6c2f302e7b48e3ad2cec575cbd34d423a9d32 07:34:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:34:32 INFO - mozversion platform_version: 54.0a1 07:34:32 INFO - using testdate: 1488382472 07:34:32 INFO - actual date: 1488382472 07:34:32 INFO - starting webserver on 'localhost:49241' 07:34:32 INFO - SUITE-START | Running 2 tests 07:34:32 INFO - TEST-START | dromaeo_css 07:34:32 INFO - operating with platform_type : mac_ 07:34:32 INFO - Initialising browser for dromaeo_css test... 07:34:32 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpRx1WXI/profile http://localhost:49241/getInfo.html) 07:34:32 INFO - PROCESS | 1641 | 1488382472987 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 07:34:32 INFO - PROCESS | 1641 | 1488382472988 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 07:34:33 INFO - PROCESS | 1641 | 1488382473030 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 07:34:35 INFO - PROCESS | 1641 | 2017-03-01 07:34:35.466 plugin-container[1643:7927] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 07:34:35 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:34:35 INFO - PROCESS | 1641 | 2017-03-01 07:34:35.469 plugin-container[1643:7927] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 07:34:35 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:34:35 INFO - PROCESS | 1641 | 2017-03-01 07:34:35.469 plugin-container[1643:7927] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:34:35 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 07:34:35 INFO - PROCESS | 1641 | colorDepth:24 07:34:35 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 07:34:35 INFO - PROCESS | 1641 | __metrics 07:34:35 ERROR - PROCESS | 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 07:34:35 INFO - PROCESS | 1641 | 1488382475658 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 07:34:36 INFO - TEST-INFO | 1641: exit 0 07:34:36 INFO - Browser initialized. 07:34:36 INFO - Running cycle 1/1 for dromaeo_css test... 07:34:36 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpRx1WXI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:34:36 INFO - PROCESS | 1644 | 1488382476800 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 07:34:36 INFO - PROCESS | 1644 | 1488382476801 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 07:34:39 INFO - PROCESS | 1644 | 2017-03-01 07:34:39.082 plugin-container[1645:8061] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 07:34:39 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:34:39 INFO - PROCESS | 1644 | 2017-03-01 07:34:39.083 plugin-container[1645:8061] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4f, name = 'com.apple.CFPasteboardClient' 07:34:39 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:34:39 INFO - PROCESS | 1644 | 2017-03-01 07:34:39.083 plugin-container[1645:8061] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:34:48 INFO - PROCESS | 1644 | RSS: Main: 226050048 07:34:48 INFO - PROCESS | 1644 | 07:37:52 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 07:37:53 INFO - PROCESS | 1644 | RSS: Main: 249880576 07:37:53 INFO - PROCESS | 1644 | 07:39:37 INFO - PROCESS | 1644 | ************************* 07:39:37 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 07:39:37 INFO - PROCESS | 1644 | 07:39:37 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:39:37 INFO - PROCESS | 1644 | Full stack: 07:39:37 INFO - PROCESS | 1644 | ************************* 07:40:55 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 07:40:56 INFO - PROCESS | 1644 | RSS: Main: 256167936 07:40:56 INFO - PROCESS | 1644 | 07:43:58 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 07:43:59 INFO - PROCESS | 1644 | RSS: Main: 268554240 07:43:59 INFO - PROCESS | 1644 | 07:47:01 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 07:47:02 INFO - PROCESS | 1644 | RSS: Main: 261398528 07:47:02 INFO - PROCESS | 1644 | 07:50:05 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 07:50:05 INFO - PROCESS | 1644 | RSS: Main: 271048704 07:50:05 INFO - PROCESS | 1644 | 07:53:08 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 07:53:08 INFO - PROCESS | 1644 | RSS: Main: 265125888 07:53:08 INFO - PROCESS | 1644 | 07:53:08 INFO - PROCESS | 1644 | __start_tp_report 07:53:08 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 07:53:08 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 07:53:08 INFO - PROCESS | 1644 | |i|pagename|runs| 07:53:08 INFO - PROCESS | 1644 | |0;dojo.html;3050.45091883461;3434.622191558928;3448.9482657760154;3450.8964731058077;3506.158521954731;4504.414426124607;5845.035569131093;5948.613340132888;5971.4625683688455;6061.030235162375;5971.865345584336;5985.162077309169;6018.608790428619;6054.424829641177;6123.357047510014;5256.395514515842;5382.8923421531545;5425.755840987145;5489.368722596892;5551.944480555203;5493.368262649787;5513.7794488220425;5519.917201241985;5532.585056120787;5536.058869992091;4426.269665505188;5326.6005049621435;5429.809956651498;5434.701091439969;5505.064139096323;5381.2197231401615;5442.6462279952075;5444.292242008553;5451.155070964007;5470.671759694431;4417.646588272963;5382.650127741685;5443.237946687467;5483.7806487740445;5485.396606373296;5642.774289028475;5836.620619659709;5886.234789477383;5985.431384018511;6036.728347224342;6306.779262725782;6321.1150438938585;6321.494280457552;6326.49388048955;6338.207724034496;6389.872202555923;6416.679166041679;6430.164078669743;6433.292337842833;6447.709853056624;5928.881422371528;5948.226730525004;6009.519238460913;6137.447629713302;6149.29283132441;5488.533474654699;6362.204724409449;6462.1276127723095;6514.413702766679;6590.439812615981;5977.163197152402;6088.786892458743;6149.938500615026;6206.782762603378;6264.404881536247;6311.116443697886;6367.7452901884335;6378.553501254868;6388.329225431356;6405.391487808651;6126.693665316717;6309;6326.145970294044;6366.108744775735;6372.980323148367;6118.9846826261555;6227.564070515021;6245.281792593819;6299.96850015747;6305.243370795442;5053.848384548396;5116.923246151311;5125.743712814345;5127.589792816566;5137.152369858932;3851.3645248533676;4563.338315944169;4584.243599805995;4615.26155815068;4631.95368046322;5933;5982.371850955587;5996.730147143302;6044.969775151184;6064.302605200459;6271.7804876830005;6362;6421.678916054178;6423.302213070521;6424.26120996082;6097.3292937776805;6132.386761323832;6143.96928015357;6173.351798061139;6193.752249909953;5492.038893193675;5562.3047119110115;5681.744321505542;5699.943000570024;5730.426957304319;6432.51756118293;6455.838604034937;6522.543421960418;6538.83652908662;6641.136652235178;6378.77674281398;6394.4564712;6400.839979000376;6406.487481001415;6421.229452465547;6543.443807276435;6647.135872336758;6649.933500664932;6659.467242620675;6671.766488172891;6156.569040167325;6163.876722465436;6165.537584681257;6172.722227499863;6228.626282423069;6090.23872015998;6197.287311959182;6230.252369715663;6232.563720539519;6236.064590311308;6122.123717501779;6125.815438131279;6137.989923150331;6142.498775697809;6209.285932117864;6215.782447614311;6218.036204388327;6233.0338797486465;6236.407541283571;6238.6880655967925;4917.385326834145;6106.369660900634;6116.3272040076445;6121.265448146251;6127.586602260358;5291.232771248225;6149.723262453111;6223.813285601439;6226.40849119333;6243.875122497616;6177.876442471035;6202.658853763086;6203.751849925952;6214.689265536796;6217.937820621736 07:53:08 INFO - PROCESS | 1644 | |1;ext.html;736.6243215959862;785.3834739729311;816.2612835383977;898.8427025270576;943.9292053096016;36988.52045918164;38074.619253807454;39122.21755564887;39243.607563924386;40033;30437.543436848464;37339.62660373395;37503.06242343943;37583.248335033284;37625.05937351567;7726.690932362699;7768.961155194216;10121.190304775637;10549.6307629233;10569.682909512689;10431.73920651986;10527.315724477923;10527.420991845445;10528.631497897575;10590.364578125298;10542.420166890817;10554.419506927115;10564.788704225912;10568;10570.625818643566;10471.005254500848;10506.632267870626;10508.316959397614;10524.210684198715;10539.63111291101;8671.393002489829;10285.537150828231;10311.48442577868;10354.482275886176;10367.89632103677;33235.169120771934;33285.334293314;33388.499172512435;33457.330853382795;33503.832480837686;3704.555453345589;3726.5341832270965;3732.477453156533;3753.7184711146774;3773.3208022555928;24790.504189916282;24916.50166996668;24975.750242497706;25055;25679.743202567923;3035.650900146489;3066.402051599939;3108.627139553587;3109.440300745865;3178.8569514371907;291726;331148.3442582796;333505;335715;341330.1733965332;33966.66033339635;38771.6122838768;42376.78811605922;42821.1435771289;42874.57125428768;40320.193596127916;44029;44052.55947440548;44430.55569444329;44629;12626.305553194663;12773.808392874114;12881;12900.935495322463;12926.030547708771;8997.685081022133;11012.339259644286;11025.558977640805;11028.889711102947;11034.282771619819;9137.223336016514;11119.833202501968;11535.76928461426;11595.18833681651;11692.88307116935;4866.710321764735;4874.512548745168;5156.92264616031;5385.703786291791;5430.212619170198;918.1461241045758;1078.703356576932;1109.8180437833682;1130.3331034689409;1142.994285028586;23604.173853915032;25594.488110238064;26231.606525902127;26674.19977400681;26686;1222.6454328244888;1226.748516554122;1236.5486597391887;1281.0968267371481;1283.2428866968532;13499.257540835348;14194.574162775134;14306.926980476506;14680.119192848464;14996.850031499764;476943;477206;479303;480169;480877;47760;48047.79880503016;48161.75919120382;48183.03633927372;48192;1363.8158848555518;1370.0820450298124;1404.3259235566984;1405.0656313551578;1406.781948797938;26116.73883261219;26448.7355126454;26787.732122679303;26981.595276071268;27061.458770824865;37520;37595.624043760305;37749;37792.43311350277;37820.43269351017;464915;467363;471613;472599;473029;21663.675044874024;21683.566328673653;21732.34802955914;21743.56512869702;21857.125714971222;35584.64415355813;36139;36578.634213657526;36626.81686591657;36699.44950825686;24334.51330973406;29637;30365.544516832706;30895;31389;30157.547636785028;30478.390432191674;31027.379452410372;31209.8439507801;31698.524522131724;30232.24419389445;30561.23596910095;30838.845805770827;30919.690803091682;30922.845385773828;24809.751902480744;26450.470990579695;27699.72300277052;27991.68158049964;28424.57363139596;21678;21708.67436988415;21743.565128697654;21746.89126554357;21820 07:53:08 INFO - PROCESS | 1644 | |2;jquery.html;386.6133866133864;388.1712543719159;395.4463750748951;400.4973757933794;417.28852306816873;19963.500912477197;21227.787722122775;21246.57506849862;21315.147394104217;21534.784652153474;22237.2216972406;22300.776992230072;22346.21788237412;22439.887800560973;23087.884560577175;5196.740162991845;6645.4019138277545;6664.766733164316;6681.465482761368;6712.56368336059;6384.680765961706;6481.837954051163;6493.805185844432;6651.966740166293;6678.666066696645;6635.469162466991;6635.568688035262;6655.633940133291;6656.567323123982;6665.833354166136;5785.508231800302;7320.707171713125;7405.259474052578;7467.477276590641;7486.363659088929;6950.513464057518;7046.929530704679;7069.151701795766;7151.427885769126;7237.240089790602;24349.39126521851;24903.879325430218;25969.74030259692;26674.733252667418;26952.92188312465;2345.7302410222874;2860.3850172212865;2925.3564215872475;2987.731104200611;3106.2544989202656;14754.081969344024;15548.92225538865;15840.366385344572;16321.83678163227;16612.335506579722;1822.690142675735;1882.7667877540232;1983.1571582077704;2006.9197232110698;2017.2838642282072;147141;151965;153672;153761;155657;19470.8052919469;19608.117634706472;19798.50503737418;20044.498887527927;20079.698804517942;25962;31420.685793142235;31470.842645786626;31507.684923150475;31526.211844704067;8789.91210087891;9300.441973481613;9553.331266811256;9732.70801875945;10096.192304615613;8200.466969647074;8216.917830821734;8223.671053157927;8361.456505327134;8410.03284622264;9126.863097053549;9245.260379169651;9250.629974800933;9284.48935308551;9330.486823224788;3422.349753546818;3610.8946306176667;3707.629237076271;3799.3351163546267;3832.80835958201;890.470170248701;905.9184673379427;992.692265397729;999.1507218864053;1027.3733022856202;19165;19628.11673474697;20459.693104603444;20540.794592053888;20834.47913802167;977.9070988256104;979.0796651147898;1000.2698030437821;1010.3079390617304;1017.8473229015707;10227.488625568692;10759.462026898624;10803.83794243087;10844.620438284623;11023.944880275547;488992.22015559487;519126;552490;589888;628009;154038.60864869677;157392.21303893407;159168;162855;163859;968.2592816495246;1003.844404117363;1048.035807057509;1050.8266136087614;1059.0256963593513;18518.629627407645;19082.90458547754;19892.005399730246;19987.500312492302;20762.961851907647;61647.07529387152;62400.06399903914;62617;62671.6866415665;62816.685916570124;83543.32913341821;83764;84654;85179.57410212909;86028.56985715032;9128.817423651622;11420.600278990196;11655.358955257543;11767.293962362286;11839.822402663794;12590.433430495832;12849.807252891402;13114.737705246032;13120.015998799938;13261.602151935458;11157.944210278896;11235.438228088726;11418.657440276806;11440.141989350666;11449;10343.534540945526;10914.399708016135;11162.553497859995;11178.27341222818;11463.942680286546;8866.556672166236;10804.945975270073;11087.667369978913;11502.022328102204;11729.706757331134;11408.657740267805;11458.19792614509;11463;11717.355545445082;11768.94115529417;9519.43159220136;10485.21360897951;10855.294405863593;11012.614558490415;11693.415329233334 07:53:08 INFO - PROCESS | 1644 | |3;mootools.html;1463.7292100961322;1492.0450911416701;1511.9168445735488;1530.8059713423527;1554.6346608546994;9097.863532047024;9384.43693378396;10387;10956.5617375305;10970.835437468435;10369.896301036988;10730.302530335523;10739.677809665722;10940.890591094087;10954.068904143138;2295.7130358705163;2338.672585837984;2342.742298347189;2345.8827058647084;2352.000399830074;2303.8617682939075;2340.578695834749;2341.3678306857137;2346.4603141277516;2352.047420794572;2317.0268487235403;2324.523472688095;2341.356127065062;2346.671465994762;2347.295811256625;2290.977090229093;2296.1389478945393;2320.93037208884;2323.6979192705007;2325.837191396603;2256.0637335505744;2338.649202619604;2343.215022967291;2346.4368551547677;2350.7766762157567;10706.946465267702;10725;10735.731606709816;10805.675829725122;10808.891911080946;5656.490915817555;5836.737346819404;5838.4453476919625;5839.416058394169;5844.649321040752;3008.8796448142048;3301.3067255876294;3439.825116899156;3520.8239588020497;3537.8231088445473;1147.1797664669718;1158.4902642837126;1223.62067758995;1236.4559593778708;1244.6079484962208;12565.120441569094;14200.857991420056;15000.849991500054;15019.924900375538;15052.774208386883;4652.492995569558;4744.240921452551;4755.38180036593;4783.521647835259;4886.291487734258;12292.446839892047;12386.071044671506;12418.379081045912;12419.068569857296;12482.313472758904;5873.295204575478;5877.4122587740885;5877.941220587825;5882.882342353129;5883.529317654577;4380.649548036149;4922.75386230687;5165.560927321145;5195.532402083831;5212.4266330703595;3892.260470510594;3976.0855003349393;3987.4218238355274;4049.0484736086996;4049.9392509112386;1127.21095233337;1134.9092072634169;1139.3961200563715;1146.3523109443138;1147.8163493840946;698.6681326369934;827.9379046571531;836.7657056024323;929.6513807322254;937.5124935033745;5098;5730.1404789281105;5965.463108320185;6062.636241825505;6702.564333318416;1008.7679833638305;1022.7698767777191;1024.533837104116;1051.395447617623;1067.2049323254225;4318.956810431855;4321.2005778931;4439.511653718088;4451.710638808467;4571.657125715585;11610.535578576932;12652.240865547912;12882.227066376048;13147.14543554666;13181.60455186346;12469.501219951282;12992.155509891827;13022.934885325512;13168.407421666048;13405.798913016313;9738.074882886114;10019.599216031276;10023.448710321003;10024.89726130125;10026.19790416765;6352.460040896391;6377.660691254889;6468.935310647022;6611.933880661132;6668.166479190101;11705.531778728755;11974;12043.397830108635;12055.879441205827;12091.637250882488;11905.82141267899;12257.387130643254;12394.876051239373;12463.626091217278;12466.376681166086;7846.843063138819;8140.470869393471;8841.823163536821;9214.677486287948;9593.42439453635;9882.802343952937;9955.950220248853;10137.847932281169;10221.33561318512;10240.590376385158;8666.306695464475;8727;8758.693445729368;8762.649494020168;8886.555672216493;8546;8585.356098292528;8628.266597339296;8655.6970506032;8830.690925817822;7634.351080158249;7787.221277872168;7788.88316675239;7796.688132474865;7802.648880800264;5775.711214439178;5814.127880817911;5892.705364731661;5970.5223582114195;6020.668863212565;4657.301404789308;4954.826581069645;5523.005858945428;5743.6840973745175;5825 07:53:08 INFO - PROCESS | 1644 | |4;prototype.html;354.2878813584696;364.99080043679214;366.3387585408337;368.58718235576157;378.4380195409816;4812.831550895719;5137.203733421321;5244.213367994803;5406.405295417506;5439.864003399917;4764.78558464869;5107.233914912765;5305.814296499623;5444.754986025639;5504.2018907258525;4087.4073259377446;4565.360849481075;4741.241401375781;4771.90456190876;4790.281457781326;3733.309337772507;4167.999680076775;4291.9570804292025;4602.7238365697995;4719.598834099105;3860.8765179151496;4655.580997710205;4660.37084993525;4670.836520721776;4730.621550275987;4308.569143085698;4718.339432479438;4735.73953432561;4735.928961065587;4803.886290032375;3563.394222982099;4321.503027151886;4339.739615623042;4459.531749166324;4485.3944717463055;4926.11329960607;4970.080535101018;5249.790008399659;5304.336957880265;5330.573554115662;4333.934990975106;5156.303898973628;5448.482394172547;5459.344878614592;5477.315335583052;5253.317068781034;5263.157894736862;5384.9230153969365;5430.75561599725;5802.535797136219;4090.1615168890603;4420;4675.275332323496;4686.43762748469;4700.670953033289;5204.76578553966;5311.893762124736;5618.494335509824;5927.673977931212;6176.4749996250375;4308.439902812677;4389.341598760211;4782.784774685148;4788.802799300175;4815.735134567562;3948.861789837342;4329.545397733255;4372.628326592276;4658.673892827537;4662.463816661059;5031.94329190874;5224.190250511177;5238.92141617876;5242.423333433319;5268.236105764643;4723.905521889543;5058.4435712071645;5143.685341596297;5384.703841288767;5882.470577648032;1316.7425109020862;1352.6753579141132;1458.824941007086;1562.3828587707792;1582.3069315832179;1006.892418339835;1030.0111892583054;1087.4508373271447;1182.2433642468827;1196.342011893455;5172;5227;5238.92141617876;5239.738013099406;5312.857735586867;5520.641158324696;5542.11326187808;5758.911173067086;5789.363170051291;5823.165702803603;4557.772111394417;4581.70117515395;4674.74288914113;5222.738863056832;5286.3920649125075;2613.830101043426;2987.0889378739694;2988.357503136836;2993.3686141053176;2997.415503976704;4996.3504744383;5953.2494528446805;5995.52035837132;6015.578909476382;6040.486558642565;5141.871453213625;5180.860210753628;5414.512693857571;5490.670559766427;5682.176084467729;5144.38267407906;5344.812931547455;5558.360788509373;5854.151148083504;5932.6651503411385;5182.429153369973;5313.335833020872;5632.044425135037;5750.482456578928;5765.684731279615;4809.639277054165;5123.949590334048;5208.687478751287;5641.210230567806;5685.914711279252;5084.669496482717;5266.683998960075;5797.362290147996;5914.6045851430235;5970.701464926649;3685.539307586552;3978.801059947049;3984.023914140938;4088.8160032798;4500.482444518922;5135.332406787244;5485.451454854483;5745.91381129275;5761.222234998221;5813.883722325445;5188.714620695898;5368.651037682538;5733.02538568436;5775.826725198251;5832.766689332379;5720.313562372542;5762.193292938908;5798.33319168301;5825.417458254141;5877.764889404547;4600.355950166912;4733.502982186958;5467.480589344006;5618.949083584544;5643.435656434323;5000.749962501933;5116.769745361393;5831.562632802642;5833.82498525045;5854.707264636836;4689.202835517902;5331.214043295539;5666.025336698266;5680.971595141998;5729.656220626609 07:53:08 INFO - PROCESS | 1644 | |5;yui.html;669.4008862068449;714.167994286656;727.4725823777762;762.3329586611715;842.6376658037044;14712.337944792484;15781.210939453025;15807.84192158078;15808.44670436535;15809.841901580981;14166.504172353969;14478.276086195701;14698.63253418665;14700.191489468076;14736.042157259797;926.8275631326378;945.5555888732284;947.947862867542;967.8306296398138;975.512243878061;970.1995853420956;970.834958057132;971.223021582731;972.610955617755;973.78576713123;917.9495127768004;973.9967833131885;978.2418625565208;979.4564016970578;981.249344251648;957.4494665567327;966.4684423567045;974.9658761943369;975.2490582251662;976.2580438866441;710.2897102897103;729.7810656802938;731.1956847467796;733.1678544851578;734.7354952217197;15018.774718379233;15675.843241567552;15904.602384940468;15921.840781592267;16013.35946562136;11704.882951170464;13972.231527265996;14008.299585020708;14220.573382798533;14224.928875355557;11301.886981130248;11497.827532587018;11685.532578696842;11845.289282642983;11868.52525898963;3576.356255873942;3613.5663720353464;3647.2705458908326;3664;3684.778913265213;85994.57002715008;88786;90029.54985225097;90662.64006039915;91664.16671666529;8921.910780892238;11121.666350009513;11218.887811121947;11298.20912536131;11463.942680286713;22301.553968920532;22388;22562.887185563966;22711.318660439883;22743.88628056849;7702.268284512962;7833.295003449717;7846.450748447668;7933.048034235813;8079.474834135763;4198.328267477189;4274.358846173037;4356.085222103394;4425.690201685851;4432.401625780542;3438.0717206354643;3532.717382609385;3533.293341331744;3536.504889315498;3539.7699149555197;597.1818608506375;598.9341172471024;613.923259592551;622.1569772957668;622.4024936061431;653.3531803514486;665.1618960110296;682.2120450879243;703.6763088979025;722.4689852958072;9961.997559112135;10064.403014439706;10083.191041575741;10217.57721604587;10258.487075646188;651.5276424592133;676.546713701821;690.126989358462;696.3837004251274;711.7508871894898;4691.111343065443;4881.462949726;4891.789490389067;4935.3090567320605;4937.7777999990085;86633.26733465295;97243.51378243063;101927.94216173526;101976;102574;85307.86730331791;86727.1327286719;88764.6685299721;88938.11061889298;89992.6501102484;82501.44748138316;82847.585762072;83800.74298885521;84655.88360291041;85521.57239213763;9459.148676619137;9461.243100552078;9488.763568732322;9551.670520691863;9583.760405989906;42610.5738942619;52592.737036314575;53631.46368536264;54130.729346353015;55303.44696553144;65965;66761.66619166873;68016.6396672054;68089.29776755621;68757;11223.18099924628;11306.207719856615;11328.546858125912;11561;11957.521699131938;10650.786984260427;12599.748005040032;12629.873701263237;12653.766379058654;13172.47310107585;9905.257105716955;11231.494582743959;11781.646550603497;11798.646040618794;12359.62921112368;9329.533523323671;10342.554678917228;11460.484278207334;11951;12021.158518903592;11394.145439091935;11480.540778368771;11567.305961642329;11573.421328933351;11967.281963082243;9800.072556318206;9912.553935073083;9929.404235745878;9997.050280223368;10018.198544116307;6538.901916471162;8336.62485188156;8788.824223515621;9064.954675226581;9507.286953478379 07:53:08 INFO - PROCESS | 1644 | __end_tp_report 07:53:08 INFO - PROCESS | 1644 | __start_cc_report 07:53:08 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,426 07:53:08 INFO - PROCESS | 1644 | __end_cc_report 07:53:08 INFO - PROCESS | 1644 | __startTimestamp1488383588949__endTimestamp 07:53:08 INFO - PROCESS | 1644 | 07:53:08 INFO - PROCESS | 1644 | ------- Summary: start ------- 07:53:08 INFO - PROCESS | 1644 | Number of tests: 6 07:53:08 INFO - PROCESS | 1644 | 07:53:08 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5880.28 Median:6127.59 stddev:680.73 (11.1%) stddev-sans-first:645.60 07:53:08 INFO - PROCESS | 1644 | Values: 3050.5 3434.6 3448.9 3450.9 3506.2 4504.4 5845.0 5948.6 5971.5 6061.0 5971.9 5985.2 6018.6 6054.4 6123.4 5256.4 5382.9 5425.8 5489.4 5551.9 5493.4 5513.8 5519.9 5532.6 5536.1 4426.3 5326.6 5429.8 5434.7 5505.1 5381.2 5442.6 5444.3 5451.2 5470.7 4417.6 5382.7 5443.2 5483.8 5485.4 5642.8 5836.6 5886.2 5985.4 6036.7 6306.8 6321.1 6321.5 6326.5 6338.2 6389.9 6416.7 6430.2 6433.3 6447.7 5928.9 5948.2 6009.5 6137.4 6149.3 5488.5 6362.2 6462.1 6514.4 6590.4 5977.2 6088.8 6149.9 6206.8 6264.4 6311.1 6367.7 6378.6 6388.3 6405.4 6126.7 6309.0 6326.1 6366.1 6373.0 6119.0 6227.6 6245.3 6300.0 6305.2 5053.8 5116.9 5125.7 5127.6 5137.2 3851.4 4563.3 4584.2 4615.3 4632.0 5933.0 5982.4 5996.7 6045.0 6064.3 6271.8 6362.0 6421.7 6423.3 6424.3 6097.3 6132.4 6144.0 6173.4 6193.8 5492.0 5562.3 5681.7 5699.9 5730.4 6432.5 6455.8 6522.5 6538.8 6641.1 6378.8 6394.5 6400.8 6406.5 6421.2 6543.4 6647.1 6649.9 6659.5 6671.8 6156.6 6163.9 6165.5 6172.7 6228.6 6090.2 6197.3 6230.3 6232.6 6236.1 6122.1 6125.8 6138.0 6142.5 6209.3 6215.8 6218.0 6233.0 6236.4 6238.7 4917.4 6106.4 6116.3 6121.3 6127.6 5291.2 6149.7 6223.8 6226.4 6243.9 6177.9 6202.7 6203.8 6214.7 6217.9 07:53:08 INFO - PROCESS | 1644 | 07:53:08 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:54018.46 Median:30401.54 stddev:114858.05 (377.8%) stddev-sans-first:115110.59 07:53:08 INFO - PROCESS | 1644 | Values: 736.6 785.4 816.3 898.8 943.9 36988.5 38074.6 39122.2 39243.6 40033.0 30437.5 37339.6 37503.1 37583.2 37625.1 7726.7 7769.0 10121.2 10549.6 10569.7 10431.7 10527.3 10527.4 10528.6 10590.4 10542.4 10554.4 10564.8 10568.0 10570.6 10471.0 10506.6 10508.3 10524.2 10539.6 8671.4 10285.5 10311.5 10354.5 10367.9 33235.2 33285.3 33388.5 33457.3 33503.8 3704.6 3726.5 3732.5 3753.7 3773.3 24790.5 24916.5 24975.8 25055.0 25679.7 3035.7 3066.4 3108.6 3109.4 3178.9 291726.0 331148.3 333505.0 335715.0 341330.2 33966.7 38771.6 42376.8 42821.1 42874.6 40320.2 44029.0 44052.6 44430.6 44629.0 12626.3 12773.8 12881.0 12900.9 12926.0 8997.7 11012.3 11025.6 11028.9 11034.3 9137.2 11119.8 11535.8 11595.2 11692.9 4866.7 4874.5 5156.9 5385.7 5430.2 918.1 1078.7 1109.8 1130.3 1143.0 23604.2 25594.5 26231.6 26674.2 26686.0 1222.6 1226.7 1236.5 1281.1 1283.2 13499.3 14194.6 14306.9 14680.1 14996.9 476943.0 477206.0 479303.0 480169.0 480877.0 47760.0 48047.8 48161.8 48183.0 48192.0 1363.8 1370.1 1404.3 1405.1 1406.8 26116.7 26448.7 26787.7 26981.6 27061.5 37520.0 37595.6 37749.0 37792.4 37820.4 464915.0 467363.0 471613.0 472599.0 473029.0 21663.7 21683.6 21732.3 21743.6 21857.1 35584.6 36139.0 36578.6 36626.8 36699.4 24334.5 29637.0 30365.5 30895.0 31389.0 30157.5 30478.4 31027.4 31209.8 31698.5 30232.2 30561.2 30838.8 30919.7 30922.8 24809.8 26450.5 27699.7 27991.7 28424.6 21678.0 21708.7 21743.6 21746.9 21820.0 07:53:08 INFO - PROCESS | 1644 | 07:53:08 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:37574.36 Median:12716.81 stddev:95377.93 (750.0%) stddev-sans-first:95604.62 07:53:08 INFO - PROCESS | 1644 | Values: 386.6 388.2 395.4 400.5 417.3 19963.5 21227.8 21246.6 21315.1 21534.8 22237.2 22300.8 22346.2 22439.9 23087.9 5196.7 6645.4 6664.8 6681.5 6712.6 6384.7 6481.8 6493.8 6652.0 6678.7 6635.5 6635.6 6655.6 6656.6 6665.8 5785.5 7320.7 7405.3 7467.5 7486.4 6950.5 7046.9 7069.2 7151.4 7237.2 24349.4 24903.9 25969.7 26674.7 26952.9 2345.7 2860.4 2925.4 2987.7 3106.3 14754.1 15548.9 15840.4 16321.8 16612.3 1822.7 1882.8 1983.2 2006.9 2017.3 147141.0 151965.0 153672.0 153761.0 155657.0 19470.8 19608.1 19798.5 20044.5 20079.7 25962.0 31420.7 31470.8 31507.7 31526.2 8789.9 9300.4 9553.3 9732.7 10096.2 8200.5 8216.9 8223.7 8361.5 8410.0 9126.9 9245.3 9250.6 9284.5 9330.5 3422.3 3610.9 3707.6 3799.3 3832.8 890.5 905.9 992.7 999.2 1027.4 19165.0 19628.1 20459.7 20540.8 20834.5 977.9 979.1 1000.3 1010.3 1017.8 10227.5 10759.5 10803.8 10844.6 11023.9 488992.2 519126.0 552490.0 589888.0 628009.0 154038.6 157392.2 159168.0 162855.0 163859.0 968.3 1003.8 1048.0 1050.8 1059.0 18518.6 19082.9 19892.0 19987.5 20763.0 61647.1 62400.1 62617.0 62671.7 62816.7 83543.3 83764.0 84654.0 85179.6 86028.6 9128.8 11420.6 11655.4 11767.3 11839.8 12590.4 12849.8 13114.7 13120.0 13261.6 11157.9 11235.4 11418.7 11440.1 11449.0 10343.5 10914.4 11162.6 11178.3 11463.9 8866.6 10804.9 11087.7 11502.0 11729.7 11408.7 11458.2 11463.0 11717.4 11768.9 9519.4 10485.2 10855.3 11012.6 11693.4 07:53:08 INFO - PROCESS | 1644 | 07:53:08 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:6571.63 Median:2346.98 stddev:4073.57 (173.6%) stddev-sans-first:4066.91 07:53:08 INFO - PROCESS | 1644 | Values: 1463.7 1492.0 1511.9 1530.8 1554.6 9097.9 9384.4 10387.0 10956.6 10970.8 10369.9 10730.3 10739.7 10940.9 10954.1 2295.7 2338.7 2342.7 2345.9 2352.0 2303.9 2340.6 2341.4 2346.5 2352.0 2317.0 2324.5 2341.4 2346.7 2347.3 2291.0 2296.1 2320.9 2323.7 2325.8 2256.1 2338.6 2343.2 2346.4 2350.8 10706.9 10725.0 10735.7 10805.7 10808.9 5656.5 5836.7 5838.4 5839.4 5844.6 3008.9 3301.3 3439.8 3520.8 3537.8 1147.2 1158.5 1223.6 1236.5 1244.6 12565.1 14200.9 15000.8 15019.9 15052.8 4652.5 4744.2 4755.4 4783.5 4886.3 12292.4 12386.1 12418.4 12419.1 12482.3 5873.3 5877.4 5877.9 5882.9 5883.5 4380.6 4922.8 5165.6 5195.5 5212.4 3892.3 3976.1 3987.4 4049.0 4049.9 1127.2 1134.9 1139.4 1146.4 1147.8 698.7 827.9 836.8 929.7 937.5 5098.0 5730.1 5965.5 6062.6 6702.6 1008.8 1022.8 1024.5 1051.4 1067.2 4319.0 4321.2 4439.5 4451.7 4571.7 11610.5 12652.2 12882.2 13147.1 13181.6 12469.5 12992.2 13022.9 13168.4 13405.8 9738.1 10019.6 10023.4 10024.9 10026.2 6352.5 6377.7 6468.9 6611.9 6668.2 11705.5 11974.0 12043.4 12055.9 12091.6 11905.8 12257.4 12394.9 12463.6 12466.4 7846.8 8140.5 8841.8 9214.7 9593.4 9882.8 9956.0 10137.8 10221.3 10240.6 8666.3 8727.0 8758.7 8762.6 8886.6 8546.0 8585.4 8628.3 8655.7 8830.7 7634.4 7787.2 7788.9 7796.7 7802.6 5775.7 5814.1 5892.7 5970.5 6020.7 4657.3 4954.8 5523.0 5743.7 5825.0 07:53:08 INFO - PROCESS | 1644 | 07:53:08 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4724.66 Median:5150.34 stddev:1315.01 (25.5%) stddev-sans-first:1277.13 07:53:08 INFO - PROCESS | 1644 | Values: 354.3 365.0 366.3 368.6 378.4 4812.8 5137.2 5244.2 5406.4 5439.9 4764.8 5107.2 5305.8 5444.8 5504.2 4087.4 4565.4 4741.2 4771.9 4790.3 3733.3 4168.0 4292.0 4602.7 4719.6 3860.9 4655.6 4660.4 4670.8 4730.6 4308.6 4718.3 4735.7 4735.9 4803.9 3563.4 4321.5 4339.7 4459.5 4485.4 4926.1 4970.1 5249.8 5304.3 5330.6 4333.9 5156.3 5448.5 5459.3 5477.3 5253.3 5263.2 5384.9 5430.8 5802.5 4090.2 4420.0 4675.3 4686.4 4700.7 5204.8 5311.9 5618.5 5927.7 6176.5 4308.4 4389.3 4782.8 4788.8 4815.7 3948.9 4329.5 4372.6 4658.7 4662.5 5031.9 5224.2 5238.9 5242.4 5268.2 4723.9 5058.4 5143.7 5384.7 5882.5 1316.7 1352.7 1458.8 1562.4 1582.3 1006.9 1030.0 1087.5 1182.2 1196.3 5172.0 5227.0 5238.9 5239.7 5312.9 5520.6 5542.1 5758.9 5789.4 5823.2 4557.8 4581.7 4674.7 5222.7 5286.4 2613.8 2987.1 2988.4 2993.4 2997.4 4996.4 5953.2 5995.5 6015.6 6040.5 5141.9 5180.9 5414.5 5490.7 5682.2 5144.4 5344.8 5558.4 5854.2 5932.7 5182.4 5313.3 5632.0 5750.5 5765.7 4809.6 5123.9 5208.7 5641.2 5685.9 5084.7 5266.7 5797.4 5914.6 5970.7 3685.5 3978.8 3984.0 4088.8 4500.5 5135.3 5485.5 5745.9 5761.2 5813.9 5188.7 5368.7 5733.0 5775.8 5832.8 5720.3 5762.2 5798.3 5825.4 5877.8 4600.4 4733.5 5467.5 5618.9 5643.4 5000.7 5116.8 5831.6 5833.8 5854.7 4689.2 5331.2 5666.0 5681.0 5729.7 07:53:08 INFO - PROCESS | 1644 | 07:53:08 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:19731.82 Median:598.06 stddev:28206.55 (4716.4%) stddev-sans-first:28249.36 07:53:08 INFO - PROCESS | 1644 | Values: 669.4 714.2 727.5 762.3 842.6 14712.3 15781.2 15807.8 15808.4 15809.8 14166.5 14478.3 14698.6 14700.2 14736.0 926.8 945.6 947.9 967.8 975.5 970.2 970.8 971.2 972.6 973.8 917.9 974.0 978.2 979.5 981.2 957.4 966.5 975.0 975.2 976.3 710.3 729.8 731.2 733.2 734.7 15018.8 15675.8 15904.6 15921.8 16013.4 11704.9 13972.2 14008.3 14220.6 14224.9 11301.9 11497.8 11685.5 11845.3 11868.5 3576.4 3613.6 3647.3 3664.0 3684.8 85994.6 88786.0 90029.5 90662.6 91664.2 8921.9 11121.7 11218.9 11298.2 11463.9 22301.6 22388.0 22562.9 22711.3 22743.9 7702.3 7833.3 7846.5 7933.0 8079.5 4198.3 4274.4 4356.1 4425.7 4432.4 3438.1 3532.7 3533.3 3536.5 3539.8 597.2 598.9 613.9 622.2 622.4 653.4 665.2 682.2 703.7 722.5 9962.0 10064.4 10083.2 10217.6 10258.5 651.5 676.5 690.1 696.4 711.8 4691.1 4881.5 4891.8 4935.3 4937.8 86633.3 97243.5 101927.9 101976.0 102574.0 85307.9 86727.1 88764.7 88938.1 89992.7 82501.4 82847.6 83800.7 84655.9 85521.6 9459.1 9461.2 9488.8 9551.7 9583.8 42610.6 52592.7 53631.5 54130.7 55303.4 65965.0 66761.7 68016.6 68089.3 68757.0 11223.2 11306.2 11328.5 11561.0 11957.5 10650.8 12599.7 12629.9 12653.8 13172.5 9905.3 11231.5 11781.6 11798.6 12359.6 9329.5 10342.6 11460.5 11951.0 12021.2 11394.1 11480.5 11567.3 11573.4 11967.3 9800.1 9912.6 9929.4 9997.1 10018.2 6538.9 8336.6 8788.8 9065.0 9507.3 07:53:08 INFO - PROCESS | 1644 | -------- Summary: end -------- 07:53:08 INFO - PROCESS | 1644 | 07:53:08 INFO - PROCESS | 1644 | 1488383588978 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 07:53:09 INFO - TEST-INFO | 1644: exit 0 07:53:09 INFO - TEST-OK | dromaeo_css | took 1117416ms 07:53:09 INFO - TEST-START | kraken 07:53:09 INFO - operating with platform_type : mac_ 07:53:09 INFO - Initialising browser for kraken test... 07:53:09 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpXqeheL/profile http://localhost:49241/getInfo.html) 07:53:10 INFO - PROCESS | 1655 | 1488383590242 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 07:53:10 INFO - PROCESS | 1655 | 1488383590243 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 07:53:10 INFO - PROCESS | 1655 | 1488383590285 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 07:53:12 INFO - PROCESS | 1655 | 2017-03-01 07:53:12.450 plugin-container[1656:10711] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 07:53:12 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:53:12 INFO - PROCESS | 1655 | 2017-03-01 07:53:12.453 plugin-container[1656:10711] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 07:53:12 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:53:12 INFO - PROCESS | 1655 | 2017-03-01 07:53:12.453 plugin-container[1656:10711] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:53:12 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 07:53:12 INFO - PROCESS | 1655 | colorDepth:24 07:53:12 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 07:53:12 INFO - PROCESS | 1655 | __metrics 07:53:12 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 07:53:12 INFO - PROCESS | 1655 | 1488383592646 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 07:53:13 INFO - TEST-INFO | 1655: exit 0 07:53:13 INFO - Browser initialized. 07:53:13 INFO - Running cycle 1/1 for kraken test... 07:53:13 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpXqeheL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:53:13 INFO - PROCESS | 1657 | 1488383593862 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 07:53:13 INFO - PROCESS | 1657 | 1488383593863 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 07:53:15 INFO - PROCESS | 1657 | 2017-03-01 07:53:15.945 plugin-container[1658:10836] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 07:53:15 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:53:15 INFO - PROCESS | 1657 | 2017-03-01 07:53:15.945 plugin-container[1658:10836] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 07:53:15 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:53:15 INFO - PROCESS | 1657 | 2017-03-01 07:53:15.945 plugin-container[1658:10836] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:53:25 INFO - PROCESS | 1657 | RSS: Main: 227487744 07:53:25 INFO - PROCESS | 1657 | 07:53:28 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 07:53:29 INFO - PROCESS | 1657 | RSS: Main: 241053696 07:53:29 INFO - PROCESS | 1657 | 07:53:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 07:53:33 INFO - PROCESS | 1657 | RSS: Main: 249163776 07:53:33 INFO - PROCESS | 1657 | 07:53:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 07:53:37 INFO - PROCESS | 1657 | RSS: Main: 251867136 07:53:37 INFO - PROCESS | 1657 | 07:53:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 07:53:39 INFO - PROCESS | 1657 | RSS: Main: 254066688 07:53:39 INFO - PROCESS | 1657 | 07:53:41 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:53:42 INFO - PROCESS | 1657 | RSS: Main: 254636032 07:53:42 INFO - PROCESS | 1657 | 07:53:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 07:53:46 INFO - PROCESS | 1657 | RSS: Main: 260112384 07:53:46 INFO - PROCESS | 1657 | 07:53:50 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 07:53:50 INFO - PROCESS | 1657 | RSS: Main: 257290240 07:53:50 INFO - PROCESS | 1657 | 07:53:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 07:53:54 INFO - PROCESS | 1657 | RSS: Main: 258277376 07:53:54 INFO - PROCESS | 1657 | 07:53:56 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:53:57 INFO - PROCESS | 1657 | RSS: Main: 258473984 07:53:57 INFO - PROCESS | 1657 | 07:53:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:53:59 INFO - PROCESS | 1657 | RSS: Main: 258494464 07:53:59 INFO - PROCESS | 1657 | 07:54:01 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:54:02 INFO - PROCESS | 1657 | RSS: Main: 258568192 07:54:02 INFO - PROCESS | 1657 | 07:54:05 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:54:06 INFO - PROCESS | 1657 | RSS: Main: 258609152 07:54:06 INFO - PROCESS | 1657 | 07:54:09 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:54:10 INFO - PROCESS | 1657 | RSS: Main: 258371584 07:54:10 INFO - PROCESS | 1657 | 07:54:11 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 07:54:12 INFO - PROCESS | 1657 | RSS: Main: 262086656 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | __start_tp_report 07:54:12 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 07:54:12 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 07:54:12 INFO - PROCESS | 1657 | |i|pagename|runs| 07:54:12 INFO - PROCESS | 1657 | |0;ai-astar;105;116;117;119;118;117;126;162;116;119 07:54:12 INFO - PROCESS | 1657 | |1;audio-beat-detection;139;105;104;176;144;142;148;117;134;121 07:54:12 INFO - PROCESS | 1657 | |2;audio-dft;210;220;211;258;226;201;194;193;202;202 07:54:12 INFO - PROCESS | 1657 | |3;audio-fft;91;60;60;86;65;63;58;63;77;61 07:54:12 INFO - PROCESS | 1657 | |4;audio-oscillator;86;71;89;59;99;92;116;101;101;85 07:54:12 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;111;88;111;89;88;89;86;134;90;89 07:54:12 INFO - PROCESS | 1657 | |6;imaging-darkroom;153;116;112;151;133;116;109;93;127;145 07:54:12 INFO - PROCESS | 1657 | |7;imaging-desaturate;90;77;136;78;74;120;142;75;84;140 07:54:12 INFO - PROCESS | 1657 | |8;json-parse-financial;65;67;65;65;67;67;66;71;61;56 07:54:12 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;56;65;58;89;86;85;84;81;63;59 07:54:12 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;90;87;90;92;92;92;115;107;106;91 07:54:12 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;155;163;131;157;138;152;160;160;176;164 07:54:12 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;176;181;179;177;186;219;183;177;178;180 07:54:12 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;71;73;69;74;80;70;70;70;70;69 07:54:12 INFO - PROCESS | 1657 | __end_tp_report 07:54:12 INFO - PROCESS | 1657 | __start_cc_report 07:54:12 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1019 07:54:12 INFO - PROCESS | 1657 | __end_cc_report 07:54:12 INFO - PROCESS | 1657 | __startTimestamp1488383652098__endTimestamp 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | ------- Summary: start ------- 07:54:12 INFO - PROCESS | 1657 | Number of tests: 14 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:121.50 Median:118.50 stddev:15.12 (12.8%) stddev-sans-first:14.82 07:54:12 INFO - PROCESS | 1657 | Values: 105.0 116.0 117.0 119.0 118.0 117.0 126.0 162.0 116.0 119.0 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:140.50 stddev:21.95 (15.6%) stddev-sans-first:23.18 07:54:12 INFO - PROCESS | 1657 | Values: 139.0 105.0 104.0 176.0 144.0 142.0 148.0 117.0 134.0 121.0 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:211.70 Median:210.50 stddev:19.40 (9.2%) stddev-sans-first:20.56 07:54:12 INFO - PROCESS | 1657 | Values: 210.0 220.0 211.0 258.0 226.0 201.0 194.0 193.0 202.0 202.0 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:68.40 Median:64.00 stddev:11.87 (18.5%) stddev-sans-first:9.36 07:54:12 INFO - PROCESS | 1657 | Values: 91.0 60.0 60.0 86.0 65.0 63.0 58.0 63.0 77.0 61.0 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:85.50 stddev:16.22 (19.0%) stddev-sans-first:17.14 07:54:12 INFO - PROCESS | 1657 | Values: 86.0 71.0 89.0 59.0 99.0 92.0 116.0 101.0 101.0 85.0 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:97.50 Median:88.50 stddev:15.93 (18.0%) stddev-sans-first:16.12 07:54:12 INFO - PROCESS | 1657 | Values: 111.0 88.0 111.0 89.0 88.0 89.0 86.0 134.0 90.0 89.0 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:125.50 Median:139.00 stddev:19.82 (14.3%) stddev-sans-first:18.36 07:54:12 INFO - PROCESS | 1657 | Values: 153.0 116.0 112.0 151.0 133.0 116.0 109.0 93.0 127.0 145.0 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:101.60 Median:76.00 stddev:29.26 (38.5%) stddev-sans-first:30.73 07:54:12 INFO - PROCESS | 1657 | Values: 90.0 77.0 136.0 78.0 74.0 120.0 142.0 75.0 84.0 140.0 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:66.50 stddev:4.03 (6.1%) stddev-sans-first:4.27 07:54:12 INFO - PROCESS | 1657 | Values: 65.0 67.0 65.0 65.0 67.0 67.0 66.0 71.0 61.0 56.0 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:72.60 Median:82.50 stddev:13.44 (16.3%) stddev-sans-first:12.85 07:54:12 INFO - PROCESS | 1657 | Values: 56.0 65.0 58.0 89.0 86.0 85.0 84.0 81.0 63.0 59.0 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:96.20 Median:90.50 stddev:9.47 (10.5%) stddev-sans-first:9.78 07:54:12 INFO - PROCESS | 1657 | Values: 90.0 87.0 90.0 92.0 92.0 92.0 115.0 107.0 106.0 91.0 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:155.60 Median:160.00 stddev:12.95 (8.1%) stddev-sans-first:13.74 07:54:12 INFO - PROCESS | 1657 | Values: 155.0 163.0 131.0 157.0 138.0 152.0 160.0 160.0 176.0 164.0 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.60 Median:180.50 stddev:12.81 (7.1%) stddev-sans-first:13.29 07:54:12 INFO - PROCESS | 1657 | Values: 176.0 181.0 179.0 177.0 186.0 219.0 183.0 177.0 178.0 180.0 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.60 Median:70.50 stddev:3.37 (4.8%) stddev-sans-first:3.57 07:54:12 INFO - PROCESS | 1657 | Values: 71.0 73.0 69.0 74.0 80.0 70.0 70.0 70.0 70.0 69.0 07:54:12 INFO - PROCESS | 1657 | -------- Summary: end -------- 07:54:12 INFO - PROCESS | 1657 | 07:54:12 INFO - PROCESS | 1657 | 1488383652121 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 07:54:12 INFO - TEST-INFO | 1657: exit 0 07:54:13 INFO - TEST-OK | kraken | took 63291ms 07:54:13 INFO - SUITE-END | took 1180s 07:54:13 INFO - Completed test suite (00:19:40) 07:54:13 INFO - javascript benchmark 07:54:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7539.088139801337, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3050.45091883461, 3434.622191558928, 3448.9482657760154, 3450.8964731058077, 3506.158521954731, 4504.414426124607, 5845.035569131093, 5948.613340132888, 5971.4625683688455, 6061.030235162375, 5971.865345584336, 5985.162077309169, 6018.608790428619, 6054.424829641177, 6123.357047510014, 5256.395514515842, 5382.8923421531545, 5425.755840987145, 5489.368722596892, 5551.944480555203, 5493.368262649787, 5513.7794488220425, 5519.917201241985, 5532.585056120787, 5536.058869992091, 4426.269665505188, 5326.6005049621435, 5429.809956651498, 5434.701091439969, 5505.064139096323, 5381.2197231401615, 5442.6462279952075, 5444.292242008553, 5451.155070964007, 5470.671759694431, 4417.646588272963, 5382.650127741685, 5443.237946687467, 5483.7806487740445, 5485.396606373296, 5642.774289028475, 5836.620619659709, 5886.234789477383, 5985.431384018511, 6036.728347224342, 6306.779262725782, 6321.1150438938585, 6321.494280457552, 6326.49388048955, 6338.207724034496, 6389.872202555923, 6416.679166041679, 6430.164078669743, 6433.292337842833, 6447.709853056624, 5928.881422371528, 5948.226730525004, 6009.519238460913, 6137.447629713302, 6149.29283132441, 5488.533474654699, 6362.204724409449, 6462.1276127723095, 6514.413702766679, 6590.439812615981, 5977.163197152402, 6088.786892458743, 6149.938500615026, 6206.782762603378, 6264.404881536247, 6311.116443697886, 6367.7452901884335, 6378.553501254868, 6388.329225431356, 6405.391487808651, 6126.693665316717, 6309.0, 6326.145970294044, 6366.108744775735, 6372.980323148367, 6118.9846826261555, 6227.564070515021, 6245.281792593819, 6299.96850015747, 6305.243370795442, 5053.848384548396, 5116.923246151311, 5125.743712814345, 5127.589792816566, 5137.152369858932, 3851.3645248533676, 4563.338315944169, 4584.243599805995, 4615.26155815068, 4631.95368046322, 5933.0, 5982.371850955587, 5996.730147143302, 6044.969775151184, 6064.302605200459, 6271.7804876830005, 6362.0, 6421.678916054178, 6423.302213070521, 6424.26120996082, 6097.3292937776805, 6132.386761323832, 6143.96928015357, 6173.351798061139, 6193.752249909953, 5492.038893193675, 5562.3047119110115, 5681.744321505542, 5699.943000570024, 5730.426957304319, 6432.51756118293, 6455.838604034937, 6522.543421960418, 6538.83652908662, 6641.136652235178, 6378.77674281398, 6394.4564712, 6400.839979000376, 6406.487481001415, 6421.229452465547, 6543.443807276435, 6647.135872336758, 6649.933500664932, 6659.467242620675, 6671.766488172891, 6156.569040167325, 6163.876722465436, 6165.537584681257, 6172.722227499863, 6228.626282423069, 6090.23872015998, 6197.287311959182, 6230.252369715663, 6232.563720539519, 6236.064590311308, 6122.123717501779, 6125.815438131279, 6137.989923150331, 6142.498775697809, 6209.285932117864, 6215.782447614311, 6218.036204388327, 6233.0338797486465, 6236.407541283571, 6238.6880655967925, 4917.385326834145, 6106.369660900634, 6116.3272040076445, 6121.265448146251, 6127.586602260358, 5291.232771248225, 6149.723262453111, 6223.813285601439, 6226.40849119333, 6243.875122497616, 6177.876442471035, 6202.658853763086, 6203.751849925952, 6214.689265536796, 6217.937820621736], "value": 5837.149036493789, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.6243215959862, 785.3834739729311, 816.2612835383977, 898.8427025270576, 943.9292053096016, 36988.52045918164, 38074.619253807454, 39122.21755564887, 39243.607563924386, 40033.0, 30437.543436848464, 37339.62660373395, 37503.06242343943, 37583.248335033284, 37625.05937351567, 7726.690932362699, 7768.961155194216, 10121.190304775637, 10549.6307629233, 10569.682909512689, 10431.73920651986, 10527.315724477923, 10527.420991845445, 10528.631497897575, 10590.364578125298, 10542.420166890817, 10554.419506927115, 10564.788704225912, 10568.0, 10570.625818643566, 10471.005254500848, 10506.632267870626, 10508.316959397614, 10524.210684198715, 10539.63111291101, 8671.393002489829, 10285.537150828231, 10311.48442577868, 10354.482275886176, 10367.89632103677, 33235.169120771934, 33285.334293314, 33388.499172512435, 33457.330853382795, 33503.832480837686, 3704.555453345589, 3726.5341832270965, 3732.477453156533, 3753.7184711146774, 3773.3208022555928, 24790.504189916282, 24916.50166996668, 24975.750242497706, 25055.0, 25679.743202567923, 3035.650900146489, 3066.402051599939, 3108.627139553587, 3109.440300745865, 3178.8569514371907, 291726.0, 331148.3442582796, 333505.0, 335715.0, 341330.1733965332, 33966.66033339635, 38771.6122838768, 42376.78811605922, 42821.1435771289, 42874.57125428768, 40320.193596127916, 44029.0, 44052.55947440548, 44430.55569444329, 44629.0, 12626.305553194663, 12773.808392874114, 12881.0, 12900.935495322463, 12926.030547708771, 8997.685081022133, 11012.339259644286, 11025.558977640805, 11028.889711102947, 11034.282771619819, 9137.223336016514, 11119.833202501968, 11535.76928461426, 11595.18833681651, 11692.88307116935, 4866.710321764735, 4874.512548745168, 5156.92264616031, 5385.703786291791, 5430.212619170198, 918.1461241045758, 1078.703356576932, 1109.8180437833682, 1130.3331034689409, 1142.994285028586, 23604.173853915032, 25594.488110238064, 26231.606525902127, 26674.19977400681, 26686.0, 1222.6454328244888, 1226.748516554122, 1236.5486597391887, 1281.0968267371481, 1283.2428866968532, 13499.257540835348, 14194.574162775134, 14306.926980476506, 14680.119192848464, 14996.850031499764, 476943.0, 477206.0, 479303.0, 480169.0, 480877.0, 47760.0, 48047.79880503016, 48161.75919120382, 48183.03633927372, 48192.0, 1363.8158848555518, 1370.0820450298124, 1404.3259235566984, 1405.0656313551578, 1406.781948797938, 26116.73883261219, 26448.7355126454, 26787.732122679303, 26981.595276071268, 27061.458770824865, 37520.0, 37595.624043760305, 37749.0, 37792.43311350277, 37820.43269351017, 464915.0, 467363.0, 471613.0, 472599.0, 473029.0, 21663.675044874024, 21683.566328673653, 21732.34802955914, 21743.56512869702, 21857.125714971222, 35584.64415355813, 36139.0, 36578.634213657526, 36626.81686591657, 36699.44950825686, 24334.51330973406, 29637.0, 30365.544516832706, 30895.0, 31389.0, 30157.547636785028, 30478.390432191674, 31027.379452410372, 31209.8439507801, 31698.524522131724, 30232.24419389445, 30561.23596910095, 30838.845805770827, 30919.690803091682, 30922.845385773828, 24809.751902480744, 26450.470990579695, 27699.72300277052, 27991.68158049964, 28424.57363139596, 21678.0, 21708.67436988415, 21743.565128697654, 21746.89126554357, 21820.0], "value": 17472.461570216754, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.6133866133864, 388.1712543719159, 395.4463750748951, 400.4973757933794, 417.28852306816873, 19963.500912477197, 21227.787722122775, 21246.57506849862, 21315.147394104217, 21534.784652153474, 22237.2216972406, 22300.776992230072, 22346.21788237412, 22439.887800560973, 23087.884560577175, 5196.740162991845, 6645.4019138277545, 6664.766733164316, 6681.465482761368, 6712.56368336059, 6384.680765961706, 6481.837954051163, 6493.805185844432, 6651.966740166293, 6678.666066696645, 6635.469162466991, 6635.568688035262, 6655.633940133291, 6656.567323123982, 6665.833354166136, 5785.508231800302, 7320.707171713125, 7405.259474052578, 7467.477276590641, 7486.363659088929, 6950.513464057518, 7046.929530704679, 7069.151701795766, 7151.427885769126, 7237.240089790602, 24349.39126521851, 24903.879325430218, 25969.74030259692, 26674.733252667418, 26952.92188312465, 2345.7302410222874, 2860.3850172212865, 2925.3564215872475, 2987.731104200611, 3106.2544989202656, 14754.081969344024, 15548.92225538865, 15840.366385344572, 16321.83678163227, 16612.335506579722, 1822.690142675735, 1882.7667877540232, 1983.1571582077704, 2006.9197232110698, 2017.2838642282072, 147141.0, 151965.0, 153672.0, 153761.0, 155657.0, 19470.8052919469, 19608.117634706472, 19798.50503737418, 20044.498887527927, 20079.698804517942, 25962.0, 31420.685793142235, 31470.842645786626, 31507.684923150475, 31526.211844704067, 8789.91210087891, 9300.441973481613, 9553.331266811256, 9732.70801875945, 10096.192304615613, 8200.466969647074, 8216.917830821734, 8223.671053157927, 8361.456505327134, 8410.03284622264, 9126.863097053549, 9245.260379169651, 9250.629974800933, 9284.48935308551, 9330.486823224788, 3422.349753546818, 3610.8946306176667, 3707.629237076271, 3799.3351163546267, 3832.80835958201, 890.470170248701, 905.9184673379427, 992.692265397729, 999.1507218864053, 1027.3733022856202, 19165.0, 19628.11673474697, 20459.693104603444, 20540.794592053888, 20834.47913802167, 977.9070988256104, 979.0796651147898, 1000.2698030437821, 1010.3079390617304, 1017.8473229015707, 10227.488625568692, 10759.462026898624, 10803.83794243087, 10844.620438284623, 11023.944880275547, 488992.22015559487, 519126.0, 552490.0, 589888.0, 628009.0, 154038.60864869677, 157392.21303893407, 159168.0, 162855.0, 163859.0, 968.2592816495246, 1003.844404117363, 1048.035807057509, 1050.8266136087614, 1059.0256963593513, 18518.629627407645, 19082.90458547754, 19892.005399730246, 19987.500312492302, 20762.961851907647, 61647.07529387152, 62400.06399903914, 62617.0, 62671.6866415665, 62816.685916570124, 83543.32913341821, 83764.0, 84654.0, 85179.57410212909, 86028.56985715032, 9128.817423651622, 11420.600278990196, 11655.358955257543, 11767.293962362286, 11839.822402663794, 12590.433430495832, 12849.807252891402, 13114.737705246032, 13120.015998799938, 13261.602151935458, 11157.944210278896, 11235.438228088726, 11418.657440276806, 11440.141989350666, 11449.0, 10343.534540945526, 10914.399708016135, 11162.553497859995, 11178.27341222818, 11463.942680286546, 8866.556672166236, 10804.945975270073, 11087.667369978913, 11502.022328102204, 11729.706757331134, 11408.657740267805, 11458.19792614509, 11463.0, 11717.355545445082, 11768.94115529417, 9519.43159220136, 10485.21360897951, 10855.294405863593, 11012.614558490415, 11693.415329233334], "value": 11248.616499336513, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1463.7292100961322, 1492.0450911416701, 1511.9168445735488, 1530.8059713423527, 1554.6346608546994, 9097.863532047024, 9384.43693378396, 10387.0, 10956.5617375305, 10970.835437468435, 10369.896301036988, 10730.302530335523, 10739.677809665722, 10940.890591094087, 10954.068904143138, 2295.7130358705163, 2338.672585837984, 2342.742298347189, 2345.8827058647084, 2352.000399830074, 2303.8617682939075, 2340.578695834749, 2341.3678306857137, 2346.4603141277516, 2352.047420794572, 2317.0268487235403, 2324.523472688095, 2341.356127065062, 2346.671465994762, 2347.295811256625, 2290.977090229093, 2296.1389478945393, 2320.93037208884, 2323.6979192705007, 2325.837191396603, 2256.0637335505744, 2338.649202619604, 2343.215022967291, 2346.4368551547677, 2350.7766762157567, 10706.946465267702, 10725.0, 10735.731606709816, 10805.675829725122, 10808.891911080946, 5656.490915817555, 5836.737346819404, 5838.4453476919625, 5839.416058394169, 5844.649321040752, 3008.8796448142048, 3301.3067255876294, 3439.825116899156, 3520.8239588020497, 3537.8231088445473, 1147.1797664669718, 1158.4902642837126, 1223.62067758995, 1236.4559593778708, 1244.6079484962208, 12565.120441569094, 14200.857991420056, 15000.849991500054, 15019.924900375538, 15052.774208386883, 4652.492995569558, 4744.240921452551, 4755.38180036593, 4783.521647835259, 4886.291487734258, 12292.446839892047, 12386.071044671506, 12418.379081045912, 12419.068569857296, 12482.313472758904, 5873.295204575478, 5877.4122587740885, 5877.941220587825, 5882.882342353129, 5883.529317654577, 4380.649548036149, 4922.75386230687, 5165.560927321145, 5195.532402083831, 5212.4266330703595, 3892.260470510594, 3976.0855003349393, 3987.4218238355274, 4049.0484736086996, 4049.9392509112386, 1127.21095233337, 1134.9092072634169, 1139.3961200563715, 1146.3523109443138, 1147.8163493840946, 698.6681326369934, 827.9379046571531, 836.7657056024323, 929.6513807322254, 937.5124935033745, 5098.0, 5730.1404789281105, 5965.463108320185, 6062.636241825505, 6702.564333318416, 1008.7679833638305, 1022.7698767777191, 1024.533837104116, 1051.395447617623, 1067.2049323254225, 4318.956810431855, 4321.2005778931, 4439.511653718088, 4451.710638808467, 4571.657125715585, 11610.535578576932, 12652.240865547912, 12882.227066376048, 13147.14543554666, 13181.60455186346, 12469.501219951282, 12992.155509891827, 13022.934885325512, 13168.407421666048, 13405.798913016313, 9738.074882886114, 10019.599216031276, 10023.448710321003, 10024.89726130125, 10026.19790416765, 6352.460040896391, 6377.660691254889, 6468.935310647022, 6611.933880661132, 6668.166479190101, 11705.531778728755, 11974.0, 12043.397830108635, 12055.879441205827, 12091.637250882488, 11905.82141267899, 12257.387130643254, 12394.876051239373, 12463.626091217278, 12466.376681166086, 7846.843063138819, 8140.470869393471, 8841.823163536821, 9214.677486287948, 9593.42439453635, 9882.802343952937, 9955.950220248853, 10137.847932281169, 10221.33561318512, 10240.590376385158, 8666.306695464475, 8727.0, 8758.693445729368, 8762.649494020168, 8886.555672216493, 8546.0, 8585.356098292528, 8628.266597339296, 8655.6970506032, 8830.690925817822, 7634.351080158249, 7787.221277872168, 7788.88316675239, 7796.688132474865, 7802.648880800264, 5775.711214439178, 5814.127880817911, 5892.705364731661, 5970.5223582114195, 6020.668863212565, 4657.301404789308, 4954.826581069645, 5523.005858945428, 5743.6840973745175, 5825.0], "value": 5036.945056985452, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.2878813584696, 364.99080043679214, 366.3387585408337, 368.58718235576157, 378.4380195409816, 4812.831550895719, 5137.203733421321, 5244.213367994803, 5406.405295417506, 5439.864003399917, 4764.78558464869, 5107.233914912765, 5305.814296499623, 5444.754986025639, 5504.2018907258525, 4087.4073259377446, 4565.360849481075, 4741.241401375781, 4771.90456190876, 4790.281457781326, 3733.309337772507, 4167.999680076775, 4291.9570804292025, 4602.7238365697995, 4719.598834099105, 3860.8765179151496, 4655.580997710205, 4660.37084993525, 4670.836520721776, 4730.621550275987, 4308.569143085698, 4718.339432479438, 4735.73953432561, 4735.928961065587, 4803.886290032375, 3563.394222982099, 4321.503027151886, 4339.739615623042, 4459.531749166324, 4485.3944717463055, 4926.11329960607, 4970.080535101018, 5249.790008399659, 5304.336957880265, 5330.573554115662, 4333.934990975106, 5156.303898973628, 5448.482394172547, 5459.344878614592, 5477.315335583052, 5253.317068781034, 5263.157894736862, 5384.9230153969365, 5430.75561599725, 5802.535797136219, 4090.1615168890603, 4420.0, 4675.275332323496, 4686.43762748469, 4700.670953033289, 5204.76578553966, 5311.893762124736, 5618.494335509824, 5927.673977931212, 6176.4749996250375, 4308.439902812677, 4389.341598760211, 4782.784774685148, 4788.802799300175, 4815.735134567562, 3948.861789837342, 4329.545397733255, 4372.628326592276, 4658.673892827537, 4662.463816661059, 5031.94329190874, 5224.190250511177, 5238.92141617876, 5242.423333433319, 5268.236105764643, 4723.905521889543, 5058.4435712071645, 5143.685341596297, 5384.703841288767, 5882.470577648032, 1316.7425109020862, 1352.6753579141132, 1458.824941007086, 1562.3828587707792, 1582.3069315832179, 1006.892418339835, 1030.0111892583054, 1087.4508373271447, 1182.2433642468827, 1196.342011893455, 5172.0, 5227.0, 5238.92141617876, 5239.738013099406, 5312.857735586867, 5520.641158324696, 5542.11326187808, 5758.911173067086, 5789.363170051291, 5823.165702803603, 4557.772111394417, 4581.70117515395, 4674.74288914113, 5222.738863056832, 5286.3920649125075, 2613.830101043426, 2987.0889378739694, 2988.357503136836, 2993.3686141053176, 2997.415503976704, 4996.3504744383, 5953.2494528446805, 5995.52035837132, 6015.578909476382, 6040.486558642565, 5141.871453213625, 5180.860210753628, 5414.512693857571, 5490.670559766427, 5682.176084467729, 5144.38267407906, 5344.812931547455, 5558.360788509373, 5854.151148083504, 5932.6651503411385, 5182.429153369973, 5313.335833020872, 5632.044425135037, 5750.482456578928, 5765.684731279615, 4809.639277054165, 5123.949590334048, 5208.687478751287, 5641.210230567806, 5685.914711279252, 5084.669496482717, 5266.683998960075, 5797.362290147996, 5914.6045851430235, 5970.701464926649, 3685.539307586552, 3978.801059947049, 3984.023914140938, 4088.8160032798, 4500.482444518922, 5135.332406787244, 5485.451454854483, 5745.91381129275, 5761.222234998221, 5813.883722325445, 5188.714620695898, 5368.651037682538, 5733.02538568436, 5775.826725198251, 5832.766689332379, 5720.313562372542, 5762.193292938908, 5798.33319168301, 5825.417458254141, 5877.764889404547, 4600.355950166912, 4733.502982186958, 5467.480589344006, 5618.949083584544, 5643.435656434323, 5000.749962501933, 5116.769745361393, 5831.562632802642, 5833.82498525045, 5854.707264636836, 4689.202835517902, 5331.214043295539, 5666.025336698266, 5680.971595141998, 5729.656220626609], "value": 4325.896020111053, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.4008862068449, 714.167994286656, 727.4725823777762, 762.3329586611715, 842.6376658037044, 14712.337944792484, 15781.210939453025, 15807.84192158078, 15808.44670436535, 15809.841901580981, 14166.504172353969, 14478.276086195701, 14698.63253418665, 14700.191489468076, 14736.042157259797, 926.8275631326378, 945.5555888732284, 947.947862867542, 967.8306296398138, 975.512243878061, 970.1995853420956, 970.834958057132, 971.223021582731, 972.610955617755, 973.78576713123, 917.9495127768004, 973.9967833131885, 978.2418625565208, 979.4564016970578, 981.249344251648, 957.4494665567327, 966.4684423567045, 974.9658761943369, 975.2490582251662, 976.2580438866441, 710.2897102897103, 729.7810656802938, 731.1956847467796, 733.1678544851578, 734.7354952217197, 15018.774718379233, 15675.843241567552, 15904.602384940468, 15921.840781592267, 16013.35946562136, 11704.882951170464, 13972.231527265996, 14008.299585020708, 14220.573382798533, 14224.928875355557, 11301.886981130248, 11497.827532587018, 11685.532578696842, 11845.289282642983, 11868.52525898963, 3576.356255873942, 3613.5663720353464, 3647.2705458908326, 3664.0, 3684.778913265213, 85994.57002715008, 88786.0, 90029.54985225097, 90662.64006039915, 91664.16671666529, 8921.910780892238, 11121.666350009513, 11218.887811121947, 11298.20912536131, 11463.942680286713, 22301.553968920532, 22388.0, 22562.887185563966, 22711.318660439883, 22743.88628056849, 7702.268284512962, 7833.295003449717, 7846.450748447668, 7933.048034235813, 8079.474834135763, 4198.328267477189, 4274.358846173037, 4356.085222103394, 4425.690201685851, 4432.401625780542, 3438.0717206354643, 3532.717382609385, 3533.293341331744, 3536.504889315498, 3539.7699149555197, 597.1818608506375, 598.9341172471024, 613.923259592551, 622.1569772957668, 622.4024936061431, 653.3531803514486, 665.1618960110296, 682.2120450879243, 703.6763088979025, 722.4689852958072, 9961.997559112135, 10064.403014439706, 10083.191041575741, 10217.57721604587, 10258.487075646188, 651.5276424592133, 676.546713701821, 690.126989358462, 696.3837004251274, 711.7508871894898, 4691.111343065443, 4881.462949726, 4891.789490389067, 4935.3090567320605, 4937.7777999990085, 86633.26733465295, 97243.51378243063, 101927.94216173526, 101976.0, 102574.0, 85307.86730331791, 86727.1327286719, 88764.6685299721, 88938.11061889298, 89992.6501102484, 82501.44748138316, 82847.585762072, 83800.74298885521, 84655.88360291041, 85521.57239213763, 9459.148676619137, 9461.243100552078, 9488.763568732322, 9551.670520691863, 9583.760405989906, 42610.5738942619, 52592.737036314575, 53631.46368536264, 54130.729346353015, 55303.44696553144, 65965.0, 66761.66619166873, 68016.6396672054, 68089.29776755621, 68757.0, 11223.18099924628, 11306.207719856615, 11328.546858125912, 11561.0, 11957.521699131938, 10650.786984260427, 12599.748005040032, 12629.873701263237, 12653.766379058654, 13172.47310107585, 9905.257105716955, 11231.494582743959, 11781.646550603497, 11798.646040618794, 12359.62921112368, 9329.533523323671, 10342.554678917228, 11460.484278207334, 11951.0, 12021.158518903592, 11394.145439091935, 11480.540778368771, 11567.305961642329, 11573.421328933351, 11967.281963082243, 9800.072556318206, 9912.553935073083, 9929.404235745878, 9997.050280223368, 10018.198544116307, 6538.901916471162, 8336.62485188156, 8788.824223515621, 9064.954675226581, 9507.286953478379], "value": 7344.761195239037, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1593.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 117.0, 119.0, 118.0, 117.0, 126.0, 162.0, 116.0, 119.0], "value": 121.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 105.0, 104.0, 176.0, 144.0, 142.0, 148.0, 117.0, 134.0, 121.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 220.0, 211.0, 258.0, 226.0, 201.0, 194.0, 193.0, 202.0, 202.0], "value": 211.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 60.0, 60.0, 86.0, 65.0, 63.0, 58.0, 63.0, 77.0, 61.0], "value": 68.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 89.0, 59.0, 99.0, 92.0, 116.0, 101.0, 101.0, 85.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 88.0, 111.0, 89.0, 88.0, 89.0, 86.0, 134.0, 90.0, 89.0], "value": 97.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 116.0, 112.0, 151.0, 133.0, 116.0, 109.0, 93.0, 127.0, 145.0], "value": 125.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 77.0, 136.0, 78.0, 74.0, 120.0, 142.0, 75.0, 84.0, 140.0], "value": 101.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 65.0, 65.0, 67.0, 67.0, 66.0, 71.0, 61.0, 56.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 65.0, 58.0, 89.0, 86.0, 85.0, 84.0, 81.0, 63.0, 59.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 92.0, 92.0, 92.0, 115.0, 107.0, 106.0, 91.0], "value": 96.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 163.0, 131.0, 157.0, 138.0, 152.0, 160.0, 160.0, 176.0, 164.0], "value": 155.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 181.0, 179.0, 177.0, 186.0, 219.0, 183.0, 177.0, 178.0, 180.0], "value": 183.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 69.0, 74.0, 80.0, 70.0, 70.0, 70.0, 70.0, 69.0], "value": 71.6, "unit": "score"}]}]} 07:54:13 INFO - Return code: 0 07:54:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:54:13 ERROR - # TBPL SUCCESS # 07:54:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:54:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:54:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:54:13 INFO - 2017-03-01 07:54:13.447 screenresolution[1662:11237] starting screenresolution argv=screenresolution get 07:54:13 INFO - 2017-03-01 07:54:13.450 screenresolution[1662:11237] Display 0: 1600x1200x32@60 07:54:13 INFO - 2017-03-01 07:54:13.457 screenresolution[1663:11240] starting screenresolution argv=screenresolution list 07:54:13 INFO - Available Modes on Display 0 07:54:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:54:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:54:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:54:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:54:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:54:13 INFO - Intel Iris: 07:54:13 INFO - Chipset Model: Intel Iris 07:54:13 INFO - Type: GPU 07:54:13 INFO - Bus: Built-In 07:54:13 INFO - VRAM (Dynamic, Max): 1536 MB 07:54:13 INFO - Vendor: Intel (0x8086) 07:54:13 INFO - Device ID: 0x0a2e 07:54:13 INFO - Revision ID: 0x0009 07:54:13 INFO - Displays: 07:54:13 INFO - Display: 07:54:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:54:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:54:13 INFO - Display Serial Number: 1600x1200 60 07:54:13 INFO - Main Display: Yes 07:54:13 INFO - Mirror: Off 07:54:13 INFO - Online: Yes 07:54:13 INFO - Rotation: Supported 07:54:13 INFO - Return code: 0 07:54:13 INFO - Running post-action listener: _resource_record_post_action 07:54:13 INFO - [mozharness: 2017-03-01 15:54:13.693969Z] Finished run-tests step (success) 07:54:13 INFO - Running post-run listener: _resource_record_post_run 07:54:13 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 39942656; Write bytes: 515039232; Read time: 389; Write time: 1946 07:54:13 INFO - TinderboxPrint: CPU usage
54.9% 07:54:13 INFO - TinderboxPrint: I/O read bytes / time
39,942,656 / 389 07:54:13 INFO - TinderboxPrint: I/O write bytes / time
515,039,232 / 1,946 07:54:13 INFO - TinderboxPrint: CPU idle
2,169.4 (45.1%) 07:54:13 INFO - TinderboxPrint: CPU system
970.5 (20.2%) 07:54:13 INFO - TinderboxPrint: CPU user
1,665.9 (34.7%) 07:54:13 INFO - TinderboxPrint: Swap in / out
250,269,696 / 0 07:54:13 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 210418176; Write bytes: 204146688; Read time: 15907; Write time: 444 07:54:13 INFO - run-tests - Wall time: 1182s; CPU: 55.0%; Read bytes: 35224064; Write bytes: 300824576; Read time: 350; Write time: 1465 07:54:14 INFO - Running post-run listener: _upload_blobber_files 07:54:14 INFO - Blob upload gear active. 07:54:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:54:14 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:54:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:54:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:54:14 INFO - (blobuploader) - INFO - Open directory for files ... 07:54:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:54:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:54:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:54:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:54:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:54:15 INFO - (blobuploader) - INFO - Done attempting. 07:54:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:54:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:54:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:54:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:54:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:54:16 INFO - (blobuploader) - INFO - Done attempting. 07:54:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:54:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:54:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:54:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:54:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:54:17 INFO - (blobuploader) - INFO - Done attempting. 07:54:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:54:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:54:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:54:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:54:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:54:20 INFO - (blobuploader) - INFO - Done attempting. 07:54:20 INFO - (blobuploader) - INFO - Iteration through files over. 07:54:20 INFO - Return code: 0 07:54:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:54:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:54:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfe0e78928d0d96ad2e6a2a47bc23fda4b3305da968e7fd70806d354223a8239eba73cad61ae26f4b74e328e0bd2289e76573dead4d69bbada450dcdf992964d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/534a459c7078da48b33a57b56269627e808c1995e80218057476192b89186aca082026cd6d82dbee698adb951ba3972e21adc37f3ed715c65d188bb05cf90fa2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a768e140ca71f46ddc7a5e0072c8add0335f43c78fd755b69772053d83e325df550151a7a1031c2def931d298203f5b2e13676ee642be910af592fe1bedf97a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7b1c979d061d78930ee70469b40429e0f9c5ec8a24c6cef8561b2ea079e960c02cd8667a4e7e52ccfb2b59d31e08685aeeb3d63c98a5363497d24a35be3ad3e"} 07:54:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:54:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:54:20 INFO - Contents: 07:54:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfe0e78928d0d96ad2e6a2a47bc23fda4b3305da968e7fd70806d354223a8239eba73cad61ae26f4b74e328e0bd2289e76573dead4d69bbada450dcdf992964d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/534a459c7078da48b33a57b56269627e808c1995e80218057476192b89186aca082026cd6d82dbee698adb951ba3972e21adc37f3ed715c65d188bb05cf90fa2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a768e140ca71f46ddc7a5e0072c8add0335f43c78fd755b69772053d83e325df550151a7a1031c2def931d298203f5b2e13676ee642be910af592fe1bedf97a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7b1c979d061d78930ee70469b40429e0f9c5ec8a24c6cef8561b2ea079e960c02cd8667a4e7e52ccfb2b59d31e08685aeeb3d63c98a5363497d24a35be3ad3e"} 07:54:20 INFO - Running post-run listener: copy_logs_to_upload_dir 07:54:20 INFO - Copying logs to upload dir... 07:54:20 INFO - mkdir: /builds/slave/test/build/upload/logs 07:54:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.192426 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-01 07:54:20.526037) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-01 07:54:20.529265) ========= 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.fenjGYHEa8/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.JQggOq62of/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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/bfe0e78928d0d96ad2e6a2a47bc23fda4b3305da968e7fd70806d354223a8239eba73cad61ae26f4b74e328e0bd2289e76573dead4d69bbada450dcdf992964d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/534a459c7078da48b33a57b56269627e808c1995e80218057476192b89186aca082026cd6d82dbee698adb951ba3972e21adc37f3ed715c65d188bb05cf90fa2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a768e140ca71f46ddc7a5e0072c8add0335f43c78fd755b69772053d83e325df550151a7a1031c2def931d298203f5b2e13676ee642be910af592fe1bedf97a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7b1c979d061d78930ee70469b40429e0f9c5ec8a24c6cef8561b2ea079e960c02cd8667a4e7e52ccfb2b59d31e08685aeeb3d63c98a5363497d24a35be3ad3e"} 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.011193 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/bfe0e78928d0d96ad2e6a2a47bc23fda4b3305da968e7fd70806d354223a8239eba73cad61ae26f4b74e328e0bd2289e76573dead4d69bbada450dcdf992964d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/534a459c7078da48b33a57b56269627e808c1995e80218057476192b89186aca082026cd6d82dbee698adb951ba3972e21adc37f3ed715c65d188bb05cf90fa2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a768e140ca71f46ddc7a5e0072c8add0335f43c78fd755b69772053d83e325df550151a7a1031c2def931d298203f5b2e13676ee642be910af592fe1bedf97a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7b1c979d061d78930ee70469b40429e0f9c5ec8a24c6cef8561b2ea079e960c02cd8667a4e7e52ccfb2b59d31e08685aeeb3d63c98a5363497d24a35be3ad3e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-01 07:54:20.563466) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 07:54:20.563862) ========= 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.fenjGYHEa8/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.JQggOq62of/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.004983 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 07:54:20.611641) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-01 07:54:20.611955) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-01 07:54:20.612261) ========= ========= Total master_lag: 0.18 =========