builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0034 starttime: 1486435792.9 results: success (0) buildid: 20170206174556 builduid: d96eb12d788b427c9d38da8bbf3565e1 revision: af8a2573d0f1e9cc6f2ba0ab67d7a702a197f177 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 18:49:52.898462) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 18:49:52.898914) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 18:49:52.899198) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 18:49:52.926614) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 18:49:52.926931) ========= 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.hBrwAqDkyo/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.BiHhMEZe20/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-02-06 18:49:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 64.5M=0s 2017-02-06 18:49:53 (64.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.604462 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 18:49:53.556043) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 18:49:53.556359) ========= 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.hBrwAqDkyo/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.BiHhMEZe20/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.026569 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 18:49:53.606199) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 18:49:53.606524) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev af8a2573d0f1e9cc6f2ba0ab67d7a702a197f177 --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 af8a2573d0f1e9cc6f2ba0ab67d7a702a197f177 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hBrwAqDkyo/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.BiHhMEZe20/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-02-06 18:49:53,711 truncating revision to first 12 chars 2017-02-06 18:49:53,711 Setting DEBUG logging. 2017-02-06 18:49:53,711 attempt 1/10 2017-02-06 18:49:53,711 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/af8a2573d0f1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-06 18:49:54,330 unpacking tar archive at: mozilla-central-af8a2573d0f1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.907790 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 18:49:54.538239) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 18:49:54.538517) ========= script_repo_revision: af8a2573d0f1e9cc6f2ba0ab67d7a702a197f177 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 18:49:54.538886) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 18:49:54.539149) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 18:49:54.559841) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-02-06 18:49:54.560455) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hBrwAqDkyo/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.BiHhMEZe20/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:49:54 INFO - MultiFileLogger online at 20170206 18:49:54 in /builds/slave/test 18:49:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:49:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:49:54 INFO - {'append_to_log': False, 18:49:54 INFO - 'base_work_dir': '/builds/slave/test', 18:49:54 INFO - 'blob_upload_branch': 'Firefox', 18:49:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:49:54 INFO - 'branch': 'Firefox', 18:49:54 INFO - 'buildbot_json_path': 'buildprops.json', 18:49:54 INFO - 'config_files': ('talos/mac_config.py',), 18:49:54 INFO - 'default_actions': ('clobber', 18:49:54 INFO - 'read-buildbot-config', 18:49:54 INFO - 'download-and-extract', 18:49:54 INFO - 'populate-webroot', 18:49:54 INFO - 'create-virtualenv', 18:49:54 INFO - 'install', 18:49:54 INFO - 'run-tests'), 18:49:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:49:54 INFO - 'download_minidump_stackwalk': True, 18:49:54 INFO - 'download_symbols': 'ondemand', 18:49:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:49:54 INFO - 'tooltool.py': '/tools/tooltool.py', 18:49:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:49:54 INFO - '/tools/misc-python/virtualenv.py')}, 18:49:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:49:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:49:54 INFO - 'installer_path': 'installer.exe', 18:49:54 INFO - 'log_level': 'info', 18:49:54 INFO - 'log_name': 'talos', 18:49:54 INFO - 'log_to_console': True, 18:49:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:49:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:49:54 INFO - 'opt_config_files': (), 18:49:54 INFO - 'pip_index': False, 18:49:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:49:54 INFO - 'cmd': ('bash', 18:49:54 INFO - '-c', 18:49:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:49:54 INFO - 'enabled': True, 18:49:54 INFO - 'halt_on_failure': False, 18:49:54 INFO - 'name': 'check_screen_resolution'},), 18:49:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:49:54 INFO - 'cmd': ('bash', 18:49:54 INFO - '-c', 18:49:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:49:54 INFO - 'enabled': True, 18:49:54 INFO - 'halt_on_failure': False, 18:49:54 INFO - 'name': 'check_screen_resolution'},), 18:49:54 INFO - 'run_cmd_checks_enabled': True, 18:49:54 INFO - 'sps_profile': False, 18:49:54 INFO - 'sps_profile_interval': 0, 18:49:54 INFO - 'suite': 'dromaeojs', 18:49:54 INFO - 'system_bits': '32', 18:49:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:49:54 INFO - 'title': 't-yosemite-r7-0034', 18:49:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:49:54 INFO - 'use_talos_json': True, 18:49:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:49:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:49:54 INFO - 'work_dir': 'build'} 18:49:54 INFO - [mozharness: 2017-02-07 02:49:54.785202Z] Running clobber step. 18:49:54 INFO - Running pre-action listener: _resource_record_pre_action 18:49:54 INFO - Running main action method: clobber 18:49:54 INFO - rmtree: /builds/slave/test/build 18:49:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:49:57 INFO - Running post-action listener: _resource_record_post_action 18:49:57 INFO - [mozharness: 2017-02-07 02:49:57.305057Z] Finished clobber step (success) 18:49:57 INFO - [mozharness: 2017-02-07 02:49:57.305333Z] Running read-buildbot-config step. 18:49:57 INFO - Running pre-action listener: _resource_record_pre_action 18:49:57 INFO - Running main action method: read_buildbot_config 18:49:57 INFO - Using buildbot properties: 18:49:57 INFO - { 18:49:57 INFO - "project": "", 18:49:57 INFO - "product": "firefox", 18:49:57 INFO - "script_repo_revision": "production", 18:49:57 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:49:57 INFO - "repository": "", 18:49:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:49:57 INFO - "buildid": "20170206174556", 18:49:57 INFO - "pgo_build": "False", 18:49:57 INFO - "basedir": "/builds/slave/test", 18:49:57 INFO - "buildnumber": 128, 18:49:57 INFO - "slavename": "t-yosemite-r7-0034", 18:49:57 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 18:49:57 INFO - "platform": "yosemite_r7", 18:49:57 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:49:57 INFO - "branch": "mozilla-central", 18:49:57 INFO - "repo_path": "mozilla-central", 18:49:57 INFO - "revision": "af8a2573d0f1e9cc6f2ba0ab67d7a702a197f177", 18:49:57 INFO - "stage_platform": "macosx64", 18:49:57 INFO - "builduid": "d96eb12d788b427c9d38da8bbf3565e1", 18:49:57 INFO - "slavebuilddir": "test" 18:49:57 INFO - } 18:49:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 18:49:57 INFO - Running post-action listener: _resource_record_post_action 18:49:57 INFO - [mozharness: 2017-02-07 02:49:57.311149Z] Finished read-buildbot-config step (success) 18:49:57 INFO - [mozharness: 2017-02-07 02:49:57.311382Z] Running download-and-extract step. 18:49:57 INFO - Running pre-action listener: _resource_record_pre_action 18:49:57 INFO - Running main action method: download_and_extract 18:49:57 INFO - mkdir: /builds/slave/test/build/tests 18:49:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:57 INFO - trying https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 18:49:57 INFO - Downloading https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 18:49:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/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 18:49:58 INFO - Downloaded 1199 bytes. 18:49:58 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 18:49:58 INFO - Using the following test package requirements: 18:49:58 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 18:49:58 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:49:58 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 18:49:58 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:49:58 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 18:49:58 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:49:58 INFO - u'jsshell-mac.zip'], 18:49:58 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:49:58 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 18:49:58 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 18:49:58 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:49:58 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 18:49:58 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:49:58 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 18:49:58 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:49:58 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 18:49:58 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:49:58 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 18:49:58 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:49:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 18:49:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:49:58 INFO - Fetch https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 18:49:59 INFO - Expected file size: 34852384 18:49:59 INFO - Obtained file size: 34852384 18:50:07 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 18:50:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 18:50:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:50:07 INFO - Fetch https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 18:50:08 INFO - Expected file size: 34852384 18:50:08 INFO - Obtained file size: 34852384 18:50:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 18:50:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:50:15 INFO - Fetch https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 18:50:16 INFO - Expected file size: 14403454 18:50:16 INFO - Obtained file size: 14403454 18:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:16 INFO - trying https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:50:16 INFO - Downloading https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 18:50:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 18:50:21 INFO - Downloaded 70248249 bytes. 18:50:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:50:21 INFO - mkdir: /builds/slave/test/properties 18:50:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:50:21 INFO - Writing to file /builds/slave/test/properties/build_url 18:50:21 INFO - Contents: 18:50:21 INFO - build_url:https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:50:22 INFO - Running post-action listener: _resource_record_post_action 18:50:22 INFO - Running post-action listener: set_extra_try_arguments 18:50:22 INFO - [mozharness: 2017-02-07 02:50:22.141196Z] Finished download-and-extract step (success) 18:50:22 INFO - [mozharness: 2017-02-07 02:50:22.141321Z] Running populate-webroot step. 18:50:22 INFO - Running pre-action listener: _resource_record_pre_action 18:50:22 INFO - Running main action method: populate_webroot 18:50:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:50:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:50:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:50:22 INFO - '--tppagecycles', 18:50:22 INFO - '7'], 18:50:22 INFO - 'tests': ['tsvgm']}}, 18:50:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:50:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:50:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:50:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:50:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:50:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:50:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:50:22 INFO - 'talos_options': ['--disable-e10s'], 18:50:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:50:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:50:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:50:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:50:22 INFO - 'talos_options': ['--disable-e10s'], 18:50:22 INFO - 'tests': ['damp', 'tps']}, 18:50:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:50:22 INFO - 'tests': ['damp', 'tps']}, 18:50:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:50:22 INFO - 'tests': ['dromaeo_dom']}, 18:50:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:50:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:50:22 INFO - 'tests': ['basic_compositor_video']}, 18:50:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:50:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:50:22 INFO - 'tests': ['a11yr', 18:50:22 INFO - 'ts_paint', 18:50:22 INFO - 'tpaint', 18:50:22 INFO - 'sessionrestore', 18:50:22 INFO - 'sessionrestore_no_auto_restore', 18:50:22 INFO - 'tabpaint']}, 18:50:22 INFO - 'other-e10s': {'tests': ['a11yr', 18:50:22 INFO - 'ts_paint', 18:50:22 INFO - 'tpaint', 18:50:22 INFO - 'sessionrestore', 18:50:22 INFO - 'sessionrestore_no_auto_restore', 18:50:22 INFO - 'tabpaint']}, 18:50:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:50:22 INFO - 'ts_paint', 18:50:22 INFO - 'tpaint', 18:50:22 INFO - 'sessionrestore', 18:50:22 INFO - 'sessionrestore_no_auto_restore', 18:50:22 INFO - 'tabpaint']}, 18:50:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:50:22 INFO - 'ts_paint', 18:50:22 INFO - 'tpaint', 18:50:22 INFO - 'sessionrestore', 18:50:22 INFO - 'sessionrestore_no_auto_restore', 18:50:22 INFO - 'tabpaint']}, 18:50:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:50:22 INFO - 'tests': ['a11yr', 18:50:22 INFO - 'ts_paint', 18:50:22 INFO - 'tpaint', 18:50:22 INFO - 'sessionrestore', 18:50:22 INFO - 'sessionrestore_no_auto_restore', 18:50:22 INFO - 'tabpaint']}, 18:50:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:50:22 INFO - 'tests': ['a11yr', 18:50:22 INFO - 'ts_paint', 18:50:22 INFO - 'tpaint', 18:50:22 INFO - 'sessionrestore', 18:50:22 INFO - 'sessionrestore_no_auto_restore', 18:50:22 INFO - 'tabpaint']}, 18:50:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:50:22 INFO - 'tests': ['tsvgx', 18:50:22 INFO - 'tsvgr_opacity', 18:50:22 INFO - 'tart', 18:50:22 INFO - 'tscrollx', 18:50:22 INFO - 'cart', 18:50:22 INFO - 'tsvg_static']}, 18:50:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:50:22 INFO - 'tsvgr_opacity', 18:50:22 INFO - 'tart', 18:50:22 INFO - 'tscrollx', 18:50:22 INFO - 'cart', 18:50:22 INFO - 'tsvg_static']}, 18:50:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:50:22 INFO - 'talos_options': ['--disable-e10s'], 18:50:22 INFO - 'tests': ['tp5o']}, 18:50:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:50:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:50:22 INFO - 'talos_options': ['--disable-e10s', 18:50:22 INFO - '--xperf_path', 18:50:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:50:22 INFO - 'tests': ['tp5n']}, 18:50:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:50:22 INFO - 'talos_options': ['--xperf_path', 18:50:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:50:22 INFO - 'tests': ['tp5n']}}, 18:50:22 INFO - 'talos.zip': {'path': '', 18:50:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:50:22 INFO - Running post-action listener: _resource_record_post_action 18:50:22 INFO - [mozharness: 2017-02-07 02:50:22.160798Z] Finished populate-webroot step (success) 18:50:22 INFO - [mozharness: 2017-02-07 02:50:22.160941Z] Running create-virtualenv step. 18:50:22 INFO - Running pre-action listener: _resource_record_pre_action 18:50:22 INFO - Running main action method: create_virtualenv 18:50:22 INFO - Creating virtualenv /builds/slave/test/build/venv 18:50:22 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 18:50:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:50:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:50:22 INFO - Using real prefix '/tools/python27' 18:50:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:50:23 INFO - Installing distribute.............................................................................................................................................................................................done. 18:50:26 INFO - Installing pip.................done. 18:50:26 INFO - Return code: 0 18:50:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:50:26 INFO - Reading from file tmpfile_stdout 18:50:26 INFO - Output received: 18:50:26 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) 18:50:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:50:26 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')]} 18:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:26 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')]} 18:50:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:26 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 0x103325b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103328768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87cbd883f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103103d30>, '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 0x10302adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f8be8>, '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.hBrwAqDkyo/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.BiHhMEZe20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:50:26 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 18:50:26 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 18:50:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hBrwAqDkyo/Render', 18:50:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:26 INFO - 'HOME': '/Users/cltbld', 18:50:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:26 INFO - 'LOGNAME': 'cltbld', 18:50:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:26 INFO - 'MOZ_NO_REMOTE': '1', 18:50:26 INFO - 'NO_EM_RESTART': '1', 18:50:26 INFO - 'PAGER': '/bin/cat', 18:50:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:26 INFO - 'PWD': '/builds/slave/test', 18:50:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:26 INFO - 'SHELL': '/bin/bash', 18:50:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiHhMEZe20/Listeners', 18:50:26 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:50:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:26 INFO - 'USER': 'cltbld', 18:50:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:26 INFO - 'XPC_FLAGS': '0x0', 18:50:26 INFO - 'XPC_SERVICE_NAME': '0', 18:50:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:26 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 18:50:26 INFO - Cleaning up... 18:50:26 INFO - Return code: 0 18:50:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:50:26 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')]} 18:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:26 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')]} 18:50:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:26 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 0x103325b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103328768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87cbd883f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103103d30>, '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 0x10302adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f8be8>, '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.hBrwAqDkyo/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.BiHhMEZe20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:50:26 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 18:50:26 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 18:50:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hBrwAqDkyo/Render', 18:50:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:26 INFO - 'HOME': '/Users/cltbld', 18:50:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:26 INFO - 'LOGNAME': 'cltbld', 18:50:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:26 INFO - 'MOZ_NO_REMOTE': '1', 18:50:26 INFO - 'NO_EM_RESTART': '1', 18:50:26 INFO - 'PAGER': '/bin/cat', 18:50:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:26 INFO - 'PWD': '/builds/slave/test', 18:50:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:26 INFO - 'SHELL': '/bin/bash', 18:50:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiHhMEZe20/Listeners', 18:50:26 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:50:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:26 INFO - 'USER': 'cltbld', 18:50:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:26 INFO - 'XPC_FLAGS': '0x0', 18:50:26 INFO - 'XPC_SERVICE_NAME': '0', 18:50:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:26 INFO - Downloading/unpacking psutil>=3.1.1 18:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:50:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:50:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:50:30 INFO - Installing collected packages: psutil 18:50:30 INFO - Running setup.py install for psutil 18:50:31 INFO - building 'psutil._psutil_osx' extension 18:50:31 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 18:50:31 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 18:50:31 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 18:50:31 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 18:50:31 INFO - building 'psutil._psutil_posix' extension 18:50:31 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 18:50:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:50:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:50:31 INFO - ^ 18:50:32 INFO - 1 warning generated. 18:50:32 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 18:50:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:50:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:50:32 INFO - Successfully installed psutil 18:50:32 INFO - Cleaning up... 18:50:32 INFO - Return code: 0 18:50:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:32 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 0x103325b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103328768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87cbd883f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103103d30>, '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 0x10302adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f8be8>, '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.hBrwAqDkyo/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.BiHhMEZe20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:50:32 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 18:50:32 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 18:50:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hBrwAqDkyo/Render', 18:50:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:32 INFO - 'HOME': '/Users/cltbld', 18:50:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:32 INFO - 'LOGNAME': 'cltbld', 18:50:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:32 INFO - 'MOZ_NO_REMOTE': '1', 18:50:32 INFO - 'NO_EM_RESTART': '1', 18:50:32 INFO - 'PAGER': '/bin/cat', 18:50:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:32 INFO - 'PWD': '/builds/slave/test', 18:50:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:32 INFO - 'SHELL': '/bin/bash', 18:50:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiHhMEZe20/Listeners', 18:50:32 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:50:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:32 INFO - 'USER': 'cltbld', 18:50:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:32 INFO - 'XPC_FLAGS': '0x0', 18:50:32 INFO - 'XPC_SERVICE_NAME': '0', 18:50:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:50:32 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 18:50:32 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 18:50:32 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 18:50:32 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 18:50:32 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 18:50:32 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 18:50:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:50:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:50:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:50:35 INFO - Installing collected packages: mozsystemmonitor 18:50:35 INFO - Running setup.py install for mozsystemmonitor 18:50:35 INFO - Successfully installed mozsystemmonitor 18:50:35 INFO - Cleaning up... 18:50:35 INFO - Return code: 0 18:50:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:50: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')]} 18:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50: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')]} 18:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:35 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 0x103325b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103328768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87cbd883f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103103d30>, '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 0x10302adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f8be8>, '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.hBrwAqDkyo/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.BiHhMEZe20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:50:35 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 18:50:35 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 18:50:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hBrwAqDkyo/Render', 18:50:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:35 INFO - 'HOME': '/Users/cltbld', 18:50:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:35 INFO - 'LOGNAME': 'cltbld', 18:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:35 INFO - 'MOZ_NO_REMOTE': '1', 18:50:35 INFO - 'NO_EM_RESTART': '1', 18:50:35 INFO - 'PAGER': '/bin/cat', 18:50:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:35 INFO - 'PWD': '/builds/slave/test', 18:50:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:35 INFO - 'SHELL': '/bin/bash', 18:50:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiHhMEZe20/Listeners', 18:50:35 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:50:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:35 INFO - 'USER': 'cltbld', 18:50:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:35 INFO - 'XPC_FLAGS': '0x0', 18:50:35 INFO - 'XPC_SERVICE_NAME': '0', 18:50:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:35 INFO - Downloading/unpacking jsonschema==2.5.1 18:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:50:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:38 INFO - Downloading functools32-3.2.3-2.tar.gz 18:50:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:50:39 INFO - warning: no files found matching '*.txt' 18:50:39 INFO - no previously-included directories found matching 'build' 18:50:39 INFO - no previously-included directories found matching 'dist' 18:50:39 INFO - no previously-included directories found matching '.git*' 18:50:39 INFO - Installing collected packages: jsonschema, functools32 18:50:39 INFO - Running setup.py install for functools32 18:50:39 INFO - warning: no files found matching '*.txt' 18:50:39 INFO - no previously-included directories found matching 'build' 18:50:39 INFO - no previously-included directories found matching 'dist' 18:50:39 INFO - no previously-included directories found matching '.git*' 18:50:39 INFO - Successfully installed jsonschema functools32 18:50:39 INFO - Cleaning up... 18:50:39 INFO - Return code: 0 18:50:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:50:39 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')]} 18:50:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:39 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')]} 18:50:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:39 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 0x103325b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103328768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87cbd883f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103103d30>, '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 0x10302adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f8be8>, '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.hBrwAqDkyo/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.BiHhMEZe20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:50:39 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 18:50:39 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 18:50:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hBrwAqDkyo/Render', 18:50:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:39 INFO - 'HOME': '/Users/cltbld', 18:50:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:39 INFO - 'LOGNAME': 'cltbld', 18:50:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:39 INFO - 'MOZ_NO_REMOTE': '1', 18:50:39 INFO - 'NO_EM_RESTART': '1', 18:50:39 INFO - 'PAGER': '/bin/cat', 18:50:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:39 INFO - 'PWD': '/builds/slave/test', 18:50:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:39 INFO - 'SHELL': '/bin/bash', 18:50:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiHhMEZe20/Listeners', 18:50:39 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:50:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:39 INFO - 'USER': 'cltbld', 18:50:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:39 INFO - 'XPC_FLAGS': '0x0', 18:50:39 INFO - 'XPC_SERVICE_NAME': '0', 18:50:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:50:39 INFO - Cleaning up... 18:50:39 INFO - Return code: 0 18:50:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:50:39 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')]} 18:50:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:39 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')]} 18:50:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:39 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 0x103325b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103328768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87cbd883f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103103d30>, '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 0x10302adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f8be8>, '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.hBrwAqDkyo/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.BiHhMEZe20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:50:39 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 18:50:39 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 18:50:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hBrwAqDkyo/Render', 18:50:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:39 INFO - 'HOME': '/Users/cltbld', 18:50:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:39 INFO - 'LOGNAME': 'cltbld', 18:50:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:39 INFO - 'MOZ_NO_REMOTE': '1', 18:50:39 INFO - 'NO_EM_RESTART': '1', 18:50:39 INFO - 'PAGER': '/bin/cat', 18:50:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:39 INFO - 'PWD': '/builds/slave/test', 18:50:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:39 INFO - 'SHELL': '/bin/bash', 18:50:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiHhMEZe20/Listeners', 18:50:39 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:50:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:39 INFO - 'USER': 'cltbld', 18:50:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:39 INFO - 'XPC_FLAGS': '0x0', 18:50:39 INFO - 'XPC_SERVICE_NAME': '0', 18:50:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:39 INFO - Downloading/unpacking blobuploader==1.2.4 18:50:39 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 18:50:39 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 18:50:39 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 18:50:39 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 18:50:39 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 18:50:39 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 18:50:42 INFO - Downloading blobuploader-1.2.4.tar.gz 18:50:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:50:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:50: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 18:50: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 18:50: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 18:50: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 18:50: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 18:50: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 18:50:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:50:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:50:43 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 18:50:43 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 18:50:43 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 18:50:43 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 18:50:43 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 18:50:43 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 18:50:43 INFO - Downloading docopt-0.6.1.tar.gz 18:50:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:50:43 INFO - Installing collected packages: blobuploader, requests, docopt 18:50:43 INFO - Running setup.py install for blobuploader 18:50:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:50:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:50:44 INFO - Running setup.py install for requests 18:50:44 INFO - Running setup.py install for docopt 18:50:44 INFO - Successfully installed blobuploader requests docopt 18:50:44 INFO - Cleaning up... 18:50:44 INFO - Return code: 0 18:50:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:50:44 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')]} 18:50:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:44 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')]} 18:50:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:44 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 0x103325b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103328768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87cbd883f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103103d30>, '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 0x10302adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f8be8>, '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.hBrwAqDkyo/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.BiHhMEZe20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:50:44 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 18:50:44 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 18:50:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hBrwAqDkyo/Render', 18:50:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:44 INFO - 'HOME': '/Users/cltbld', 18:50:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:44 INFO - 'LOGNAME': 'cltbld', 18:50:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:44 INFO - 'MOZ_NO_REMOTE': '1', 18:50:44 INFO - 'NO_EM_RESTART': '1', 18:50:44 INFO - 'PAGER': '/bin/cat', 18:50:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:44 INFO - 'PWD': '/builds/slave/test', 18:50:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:44 INFO - 'SHELL': '/bin/bash', 18:50:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiHhMEZe20/Listeners', 18:50:44 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:50:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:44 INFO - 'USER': 'cltbld', 18:50:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:44 INFO - 'XPC_FLAGS': '0x0', 18:50:44 INFO - 'XPC_SERVICE_NAME': '0', 18:50:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:50:45 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-hvg49v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:50:45 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-iI5nsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:50:45 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-T7jdTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:50:45 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-bAAQb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:50:45 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-aD95J1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:50:45 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-WMeBiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:50:45 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-VdKExp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:50:45 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-3dqfXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:50:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-UDtSA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:50:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ePZJJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:50:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-DLaKSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:50:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-_6B95C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:50:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-bCnNFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:50:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-KKdHnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:50:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-9MjtKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:50:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-GiTVz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:50:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-_w6IpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:50:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:50:47 INFO - Running setup.py install for manifestparser 18:50:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:50:47 INFO - Running setup.py install for mozcrash 18:50:47 INFO - Running setup.py install for mozdebug 18:50:47 INFO - Running setup.py install for mozdevice 18:50:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:50:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:50:47 INFO - Running setup.py install for mozfile 18:50:47 INFO - Running setup.py install for mozhttpd 18:50:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:50:47 INFO - Running setup.py install for mozinfo 18:50:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:50:48 INFO - Running setup.py install for mozInstall 18:50:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:50:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:50:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:50:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:50:48 INFO - Running setup.py install for mozleak 18:50:48 INFO - Running setup.py install for mozlog 18:50:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:50:48 INFO - Running setup.py install for moznetwork 18:50:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:50:48 INFO - Running setup.py install for mozprocess 18:50:48 INFO - Running setup.py install for mozprofile 18:50:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:50:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:50:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:50:49 INFO - Running setup.py install for mozrunner 18:50:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:50:49 INFO - Running setup.py install for mozscreenshot 18:50:49 INFO - Running setup.py install for moztest 18:50:49 INFO - Running setup.py install for mozversion 18:50:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:50:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:50:49 INFO - Cleaning up... 18:50:49 INFO - Return code: 0 18:50:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:50:49 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')]} 18:50:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:49 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')]} 18:50:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:49 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 0x103325b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103328768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87cbd883f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103103d30>, '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 0x10302adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f8be8>, '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.hBrwAqDkyo/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.BiHhMEZe20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:50:49 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 18:50:49 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 18:50:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hBrwAqDkyo/Render', 18:50:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:49 INFO - 'HOME': '/Users/cltbld', 18:50:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:49 INFO - 'LOGNAME': 'cltbld', 18:50:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:49 INFO - 'MOZ_NO_REMOTE': '1', 18:50:49 INFO - 'NO_EM_RESTART': '1', 18:50:49 INFO - 'PAGER': '/bin/cat', 18:50:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:49 INFO - 'PWD': '/builds/slave/test', 18:50:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:49 INFO - 'SHELL': '/bin/bash', 18:50:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiHhMEZe20/Listeners', 18:50:49 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:50:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:49 INFO - 'USER': 'cltbld', 18:50:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:49 INFO - 'XPC_FLAGS': '0x0', 18:50:49 INFO - 'XPC_SERVICE_NAME': '0', 18:50:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:50:50 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-8gSISe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:50:50 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)) 18:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:50:50 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-rZB0Jm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:50:50 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)) 18:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:50:50 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-uxUzeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:50:50 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)) 18:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:50:50 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-IsIFAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:50:50 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)) 18:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:50:50 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-8cYZ0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:50:50 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)) 18:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:50:50 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Tg0o9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:50:50 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)) 18:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:50:50 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-4jzfh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:50:50 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)) 18:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:50:50 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-d4o6Zw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:50:50 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)) 18:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:50:50 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-AmWscC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:50:51 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)) 18:50:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:50:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-iQZLTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:50:51 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)) 18:50:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:50:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ZO3ibz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:50:51 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)) 18:50:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:50:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-F8CiRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:50:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 18:50:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:50:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-d0DxOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:50:51 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)) 18:50:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:50:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-JshYvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:50:51 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)) 18:50:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:50:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-t0xvWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:50:51 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)) 18:50:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:50:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-F0fpdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:50:51 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)) 18:50:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:50:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-JqIH2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:50:51 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)) 18:50:51 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)) 18:50:51 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)) 18:50:51 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)) 18:50:51 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)) 18:50:51 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)) 18:50:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:50:51 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 18:50:51 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 18:50:51 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 18:50:51 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 18:50:51 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 18:50:51 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 18:50:54 INFO - Downloading blessings-1.6.tar.gz 18:50:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:50:54 INFO - Installing collected packages: blessings 18:50:54 INFO - Running setup.py install for blessings 18:50:54 INFO - Successfully installed blessings 18:50:54 INFO - Cleaning up... 18:50:55 INFO - Return code: 0 18:50:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:50:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:50:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:50:55 INFO - Reading from file tmpfile_stdout 18:50:55 INFO - Current package versions: 18:50:55 INFO - blessings == 1.6 18:50:55 INFO - blobuploader == 1.2.4 18:50:55 INFO - docopt == 0.6.1 18:50:55 INFO - functools32 == 3.2.3-2 18:50:55 INFO - jsonschema == 2.5.1 18:50:55 INFO - manifestparser == 1.1 18:50:55 INFO - mozInstall == 1.12 18:50:55 INFO - mozcrash == 1.0 18:50:55 INFO - mozdebug == 0.1 18:50:55 INFO - mozdevice == 0.49 18:50:55 INFO - mozfile == 1.2 18:50:55 INFO - mozhttpd == 0.7 18:50:55 INFO - mozinfo == 0.9 18:50:55 INFO - mozleak == 0.1 18:50:55 INFO - mozlog == 3.4 18:50:55 INFO - moznetwork == 0.27 18:50:55 INFO - mozprocess == 0.24 18:50:55 INFO - mozprofile == 0.28 18:50:55 INFO - mozrunner == 6.13 18:50:55 INFO - mozscreenshot == 0.1 18:50:55 INFO - mozsystemmonitor == 0.3 18:50:55 INFO - moztest == 0.8 18:50:55 INFO - mozversion == 1.4 18:50:55 INFO - psutil == 3.1.1 18:50:55 INFO - requests == 1.2.3 18:50:55 INFO - wsgiref == 0.1.2 18:50:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:50: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')]} 18:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50: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')]} 18:50:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:55 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 0x103325b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103328768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87cbd883f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103103d30>, '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 0x10302adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f8be8>, '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.hBrwAqDkyo/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.BiHhMEZe20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:50:55 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 18:50:55 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 18:50:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hBrwAqDkyo/Render', 18:50:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:55 INFO - 'HOME': '/Users/cltbld', 18:50:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:55 INFO - 'LOGNAME': 'cltbld', 18:50:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:55 INFO - 'MOZ_NO_REMOTE': '1', 18:50:55 INFO - 'NO_EM_RESTART': '1', 18:50:55 INFO - 'PAGER': '/bin/cat', 18:50:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:55 INFO - 'PWD': '/builds/slave/test', 18:50:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:55 INFO - 'SHELL': '/bin/bash', 18:50:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiHhMEZe20/Listeners', 18:50:55 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:50:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:55 INFO - 'USER': 'cltbld', 18:50:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:55 INFO - 'XPC_FLAGS': '0x0', 18:50:55 INFO - 'XPC_SERVICE_NAME': '0', 18:50:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:55 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)) 18:50:55 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)) 18:50:55 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)) 18:50:55 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)) 18:50:55 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)) 18:50:55 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)) 18:50:55 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)) 18:50:55 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)) 18:50:55 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)) 18:50:55 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)) 18:50:55 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)) 18:50:55 INFO - Cleaning up... 18:50:55 INFO - Return code: 0 18:50:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:50: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')]} 18:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50: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')]} 18:50:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103325b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103328768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87cbd883f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103103d30>, '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 0x10302adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f8be8>, '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.hBrwAqDkyo/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.BiHhMEZe20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 18:50: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', 'jsonschema'] in /builds/slave/test/build 18:50: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 jsonschema 18:50:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hBrwAqDkyo/Render', 18:50:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:55 INFO - 'HOME': '/Users/cltbld', 18:50:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:55 INFO - 'LOGNAME': 'cltbld', 18:50:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:55 INFO - 'MOZ_NO_REMOTE': '1', 18:50:55 INFO - 'NO_EM_RESTART': '1', 18:50:55 INFO - 'PAGER': '/bin/cat', 18:50:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:55 INFO - 'PWD': '/builds/slave/test', 18:50:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:55 INFO - 'SHELL': '/bin/bash', 18:50:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiHhMEZe20/Listeners', 18:50:55 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:50:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:55 INFO - 'USER': 'cltbld', 18:50:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:55 INFO - 'XPC_FLAGS': '0x0', 18:50:55 INFO - 'XPC_SERVICE_NAME': '0', 18:50:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:50:55 INFO - Cleaning up... 18:50:55 INFO - Return code: 0 18:50:55 INFO - Running post-action listener: _resource_record_post_action 18:50:55 INFO - Running post-action listener: _start_resource_monitoring 18:50:55 INFO - Starting resource monitoring. 18:50:55 INFO - [mozharness: 2017-02-07 02:50:55.977524Z] Finished create-virtualenv step (success) 18:50:55 INFO - [mozharness: 2017-02-07 02:50:55.978593Z] Running install step. 18:50:55 INFO - Running pre-action listener: _resource_record_pre_action 18:50:55 INFO - Running main action method: install 18:50:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:50:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:50:56 INFO - Reading from file tmpfile_stdout 18:50:56 INFO - Detecting whether we're running mozinstall >=1.0... 18:50:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:50:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:50:56 INFO - Reading from file tmpfile_stdout 18:50:56 INFO - Output received: 18:50:56 INFO - Usage: mozinstall [options] installer 18:50:56 INFO - Options: 18:50:56 INFO - -h, --help show this help message and exit 18:50:56 INFO - -d DEST, --destination=DEST 18:50:56 INFO - Directory to install application into. [default: 18:50:56 INFO - "/builds/slave/test"] 18:50:56 INFO - --app=APP Application being installed. [default: firefox] 18:50:56 INFO - mkdir: /builds/slave/test/build/application 18:50:56 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'] 18:50:56 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 18:51:19 INFO - Reading from file tmpfile_stdout 18:51:19 INFO - Output received: 18:51:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:51:19 INFO - Running post-action listener: _resource_record_post_action 18:51:19 INFO - [mozharness: 2017-02-07 02:51:19.336100Z] Finished install step (success) 18:51:19 INFO - [mozharness: 2017-02-07 02:51:19.336283Z] Running run-tests step. 18:51:19 INFO - Running pre-action listener: _resource_record_pre_action 18:51:19 INFO - Running main action method: run_tests 18:51:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:51:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:51:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:51:19 INFO - 2017-02-06 18:51:19.351 screenresolution[1637:8293] starting screenresolution argv=screenresolution get 18:51:19 INFO - 2017-02-06 18:51:19.354 screenresolution[1637:8293] Display 0: 1600x1200x32@60 18:51:19 INFO - 2017-02-06 18:51:19.360 screenresolution[1638:8295] starting screenresolution argv=screenresolution list 18:51:19 INFO - Available Modes on Display 0 18:51:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:51:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:51:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:51:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:51:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:51:19 INFO - Intel Iris: 18:51:19 INFO - Chipset Model: Intel Iris 18:51:19 INFO - Type: GPU 18:51:19 INFO - Bus: Built-In 18:51:19 INFO - VRAM (Dynamic, Max): 1536 MB 18:51:19 INFO - Vendor: Intel (0x8086) 18:51:19 INFO - Device ID: 0x0a2e 18:51:19 INFO - Revision ID: 0x0009 18:51:19 INFO - Displays: 18:51:19 INFO - Display: 18:51:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:51:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:51:19 INFO - Display Serial Number: 1600x1200 60 18:51:19 INFO - Main Display: Yes 18:51:19 INFO - Mirror: Off 18:51:19 INFO - Online: Yes 18:51:19 INFO - Rotation: Supported 18:51:19 INFO - Return code: 0 18:51:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:51:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:51:19 INFO - Python 2.7.3 18:51:19 INFO - Return code: 0 18:51:19 INFO - grabbing minidump binary from tooltool 18:51:19 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')]} 18:51:19 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 0x103103d30>, '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 0x10302adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:51:19 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 18:51:19 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 18:51:19 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 18:51:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:51:19 INFO - Return code: 0 18:51:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:51:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:51:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:51:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:51:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:51:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:51:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0034 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:51:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hBrwAqDkyo/Render', 18:51:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:19 INFO - 'HOME': '/Users/cltbld', 18:51:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:19 INFO - 'LOGNAME': 'cltbld', 18:51:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:51:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:51:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:19 INFO - 'MOZ_NO_REMOTE': '1', 18:51:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:51:19 INFO - 'NO_EM_RESTART': '1', 18:51:19 INFO - 'PAGER': '/bin/cat', 18:51:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:19 INFO - 'PWD': '/builds/slave/test', 18:51:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:51:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:19 INFO - 'SHELL': '/bin/bash', 18:51:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiHhMEZe20/Listeners', 18:51:19 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 18:51:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:19 INFO - 'USER': 'cltbld', 18:51:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:19 INFO - 'XPC_FLAGS': '0x0', 18:51:19 INFO - 'XPC_SERVICE_NAME': '0', 18:51:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:51:19 INFO - mozversion application_buildid: 20170206174556 18:51:19 INFO - mozversion application_changeset: af8a2573d0f1e9cc6f2ba0ab67d7a702a197f177 18:51:19 INFO - mozversion application_display_name: Nightly 18:51:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:51:19 INFO - mozversion application_name: Firefox 18:51:19 INFO - mozversion application_remotingname: firefox 18:51:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:51:19 INFO - mozversion application_vendor: Mozilla 18:51:19 INFO - mozversion application_version: 54.0a1 18:51:19 INFO - mozversion platform_buildid: 20170206174556 18:51:19 INFO - mozversion platform_changeset: af8a2573d0f1e9cc6f2ba0ab67d7a702a197f177 18:51:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:51:19 INFO - mozversion platform_version: 54.0a1 18:51:19 INFO - using testdate: 1486435879 18:51:19 INFO - actual date: 1486435879 18:51:19 INFO - starting webserver on 'localhost:49242' 18:51:19 INFO - SUITE-START | Running 2 tests 18:51:19 INFO - TEST-START | dromaeo_css 18:51:19 INFO - operating with platform_type : mac_ 18:51:19 INFO - Initialising browser for dromaeo_css test... 18:51:19 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpo2ICOw/profile http://localhost:49242/getInfo.html) 18:51:22 INFO - PROCESS | 1648 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 18:51:22 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 18:51:22 INFO - PROCESS | 1648 | colorDepth:24 18:51:22 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 18:51:22 INFO - PROCESS | 1648 | __metrics 18:51:22 INFO - PROCESS | 1648 | 1486435882851 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 18:51:22 INFO - PROCESS | 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:51:22 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:51:23 INFO - TEST-INFO | 1648: exit 0 18:51:23 INFO - Browser initialized. 18:51:23 INFO - Running cycle 1/1 for dromaeo_css test... 18:51:23 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpo2ICOw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:51:35 INFO - PROCESS | 1651 | RSS: Main: 236605440 18:51:35 INFO - PROCESS | 1651 | 18:54:37 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 18:54:39 INFO - PROCESS | 1651 | RSS: Main: 339750912 18:54:39 INFO - PROCESS | 1651 | 18:56:25 INFO - PROCESS | 1651 | ************************* 18:56:25 INFO - PROCESS | 1651 | A coding exception was thrown and uncaught in a Task. 18:56:25 INFO - PROCESS | 1651 | 18:56:25 ERROR - PROCESS | 1651 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:56:25 INFO - PROCESS | 1651 | Full stack: 18:56:25 INFO - PROCESS | 1651 | ************************* 18:57:42 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 18:57:43 INFO - PROCESS | 1651 | RSS: Main: 369713152 18:57:43 INFO - PROCESS | 1651 | 19:00:46 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 19:00:46 INFO - PROCESS | 1651 | RSS: Main: 361553920 19:00:46 INFO - PROCESS | 1651 | 19:03:49 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 19:03:49 INFO - PROCESS | 1651 | RSS: Main: 363122688 19:03:49 INFO - PROCESS | 1651 | 19:06:53 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 19:06:53 INFO - PROCESS | 1651 | RSS: Main: 329007104 19:06:53 INFO - PROCESS | 1651 | 19:09:57 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 19:09:57 INFO - PROCESS | 1651 | RSS: Main: 517480448 19:09:57 INFO - PROCESS | 1651 | 19:09:57 INFO - PROCESS | 1651 | __start_tp_report 19:09:57 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 19:09:57 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 19:09:57 INFO - PROCESS | 1651 | |i|pagename|runs| 19:09:57 INFO - PROCESS | 1651 | |0;dojo.html;2781.6940136584976;2811.74694277515;2837.24812924575;2842.8294302341874;2864.312564984404;4661.184292748772;4764.094821983829;4866.975665121674;4899.461059283481;4968.627352948525;4749.833755818547;4774.618030557555;4830.227163653816;4868.732219727914;4943.1102401567705;4158.812853421603;4424.867253982386;4452.487963884146;4452.933206001912;4634.814607415717;4496.043481736067;4569.520200378962;4592.494825569184;4612.515685852987;4613.1926912790295;4303.935440968388;4320.287152619814;4328.307470804656;4383.605475507188;4487.394201782767;4378.124375124956;4528.977355113237;4554.476235232957;4598.241290187115;4614.423197100363;4448.488423831236;4470.731756094645;4492.932606010912;4531.501534831166;4571.634269258451;4645.65157613177;4668.85993420198;4873.951260487385;4883.413990321184;4898.21628539432;5258.50044245796;5276.12943864262;5296.682199068068;5318.973405132988;5329.173978033411;3911.4915061041884;5416.729163541807;5505.366882808488;5532.1425179097305;5560.2493663355335;5072.467390923936;5090.10923088458;5099.923501147448;5100.438951715382;5120.820771272987;5261.868453288622;5324.334458192726;5588.692621905834;5608.915866262009;5646.265985421951;5184.48155184486;5251.291075704808;5292.153255479105;5304.363476382859;5400.594955378362;5429.592780541475;5446.945530544723;5471.261379713767;5493.7802487900835;5501.862453438696;4591.403117594691;5252.264682944391;5333.146696528537;5367.731613419313;5396.109641909119;5097.311862898536;5243.868903277448;5252.606054545848;5281.656692314988;5342.412334643186;4433.312836510346;4446.5331140230455;4456.732596044248;4470;4481.619062379734;3864.304425203434;3870.3420418528917;3877.7867217302755;3883.7087218458164;3898.278818418545;4714.222153344728;4839.782209800567;4840.273958906122;4896.069746748107;4930.778114984834;5401.351837779492;5454.400015998237;5476.1511965644595;5588.189712491747;5625.324961004624;4886.780094895738;4940.135476291634;4941.604671626261;4971.875703107451;5003.974980125076;4816.975915120402;4872.731999740048;4874.926876096862;4877.878053048631;4914.483979182206;5309.309789727388;5427.620066595379;5452.863678408072;5541.639793413416;5557.41647127062;5343.65266257692;5408.78364865401;5412.945870541244;5415.160650099241;5419.647722897999;5639.605227634026;5698.6580805151825;5708.143778433268;5751.3673495916255;5765.423457654201;4472.865814025585;5293.550048245942;5318.787248510016;5330.413654498079;5339.172428273624;4993.600511958962;5098.2862399263395;5420.457954204548;5440.047991601375;5443.101888188405;5144;5155.613329000415;5213.113770658922;5238.135707608202;5289.6032797539565;5186;5189.455107213688;5214.165733482625;5214.452482489436;5262.763175657181;4932.580730637793;5114.15616423286;5145.202493613496;5152;5163.715995620277;5061.924071138862;5127.5897928166405;5168.896622067613;5222.242774797557;5332.73336333174;5177.767000485062;5184.455632158569;5185.792568297225;5195.532402083831;5202.271681964604 19:09:57 INFO - PROCESS | 1651 | |1;ext.html;402.2879503279196;421.012725159501;427.2672366890783;431.1334218221376;448.70833957927323;28196.43607127859;29284.267893302676;37861.62138378612;39359.606403935955;39366.60633393665;39892;40222;40284;40341.59658403415;40397.596024039754;8770.298376129911;8967.775805604846;9002.369834111581;9005.864912026325;9018.368714189977;7703.191164927686;7923.881141782877;9968;10462.110720588758;10752.193585481082;9946.502674866228;10652.946735266312;10659.307145035587;10672.199585031114;10748.570057197703;7599.240075992412;10666.839997400046;10677.41274229917;10747.032767050925;10769.569217231341;7672.693092276302;10206.438645874474;10567.630132945387;10586.735331616694;10746.247762656618;38172.23655526902;38606.42090368647;38625.61374386276;38651.61348386508;38772;3821.5414150301863;3835.5589107252463;3847.8653247136217;3856.981423841717;3867.1298957734566;28431.71568284332;28507.144785656463;28903.42193156146;29199;29421;2981.4931461651354;2982.5078861987963;2984.5523171524446;2992.7456166225898;3004.1438190115714;316247;318675.4066229654;319389.40305298555;320156;321257;47384.5261547387;55444.89110217854;55958.44041559614;56549.71725141348;56583.43416565782;41089.38365924513;44732;47053;47291.5270847294;47505;12492.188007779465;14273.500427484989;14708.926455367655;15032;15042;11479.368634725004;11563.479643416067;11592.246503977214;11598.304101753922;11608.419579021183;14947.70104597902;14970.251487425585;15033;15069.924650376677;15233.238338083273;5928.940710592925;6853.897191542131;6879.6560171991205;7052.506324557232;7085.89371159433;710.9123041746137;731.4294850016995;734.9963250183715;751.4664588142371;754.9056367954006;25203;27533.586996195074;28301.31681251119;28440.431191376058;28887.56668649972;815.8531464336357;862.8878245828002;912.5528491039342;927.8561822917459;951.9857202141974;14127.78808317876;14560.271986400638;14633.707325853424;14972.775408368883;14985.0259733117;567556;751364;771478;772408;776014;39382;42346.0944257503;47004.7649761749;48986.75506622444;49297.26054109191;1280.065552146938;1293.1529847949607;1311.613074143111;1312.3962977030292;1317.6376496463358;22365.776342236368;26002;26027;26495.73504264933;26761.732382676702;41647.58352416437;41738.74783756492;41749.58250417457;41861.79069104635;41920.79039604782;361632;361790.1910490431;362359;362931;362966;20543.075561600064;20846.68729969082;20854.89572552188;21408.92955352195;22461.5507689842;38661.6133838658;39296;39779.60220397875;39855.80072099621;41352.79323603483;32287.838560807046;32335.191620209684;32357;33217.50173747444;33274;30952;32228.83885580557;33167;33819.830900845336;33877.66122338844;31376.215594610316;31633.89281375141;31944.68055319417;32785.34429311353;32962.01113966585;28037.439251214455;28993.420131596828;29188.56217156702;29447;29479.458055010837;18360.357387491375;21547.707137571797;21925.780742192375;22147.55704885861;22448.10207591678 19:09:57 INFO - PROCESS | 1651 | |2;jquery.html;342.66761241595657;349.58574089703706;349.8565588108876;371.8624109079641;383.9616038396161;18191.909040454797;18842;19439;19698;19895.701564476545;19863.30478433255;20248.785072895636;20394.082266298014;20639.380818575428;20790.89604551979;4363.825446982117;5524.558035357172;5776.82669519913;5792.68140252286;5801.47786699197;6214.968925155391;6373.7450501979865;6419.037144428326;6423.261324947644;6423.93576064238;6008.879822403549;6219.533534984872;6228.314885362606;6263.342349053352;6381.7766378176775;6238.344973777756;6286.87426251477;6292.528060395489;6296.181496405484;6303.464205542535;5261.552768014723;5466.671999680032;5521.834344969658;5534.612577119603;5549.972250138764;24501.63247551288;24549.631755523682;24596;24645.876770616032;24863.50272994548;2229.7547269800307;2238.753737088919;2245.921392751246;2247.5954328220914;2286.371247906822;14014.579562613037;14550.854491455057;14662.973591848468;14749.410023599043;15442.536723898302;1709.529879283207;1739.0611856873334;1885.198790514026;1894.2991093295568;1896.2035944903173;110572;132488;133154.33422832823;136716;136857.5227697635;10388.272820902615;10497.580096796042;10879.184061195441;11317.773644527064;11325.603603873826;15320.080795152326;15360.23198840076;15379.692406151815;15393.538193854203;15430.691386172213;7650;7939.563324017235;7940.761777146695;8013.599320033975;8065.072516660542;7436.962815185998;7455.776326710207;7493.850122997618;7589.165191828894;7630.465867389229;3790.5830358660523;4522.819087236539;4594.1271158479785;4707.599854012379;4743.644226682944;1877.296013994752;1957.1779852462;1960.2061165228106;1962.823345898876;1969.7439332886634;558.5531574740191;623.7723231020644;696.098552374676;715.2811424518326;741.6180666956521;19720.605587888163;20589;21160.682589761167;21242.681359779617;21311.36065917994;766.9079710434784;782.8160382310152;793.1434051224663;810.13720387788;810.8864758933754;10546;10710.94573890205;10839.241253112206;10848.651777338708;10906.27343164209;611447;619592;634184;657472;673696;95733;100860.4956975225;107989.4600527008;114672;119358.40320798515;784.4352066512103;850.2815121222557;922.6171138977384;922.9953850230706;923.0262073512508;18782.436526904214;19066.52333691669;19118.713219301444;19149.042547872272;19195.80804191996;54808.72595636996;55877.44122558722;55983;56139.15791263216;56173.43826561682;69304.65347673229;75387.62306188434;79207.60396198212;79478;82053.17946820694;7860.4104692147175;8078.636461359135;8187.099419063779;8225.465344752572;8382.580870956306;10866.836997444887;11109.111271098454;11246.943765281121;12102.697432564257;12619.621411357673;9222.861657075015;10106.898931010595;10638.787224255626;10735.785284294427;11058.502367393326;11011.559537618727;11018.889811102106;11052.834207486734;11358.659240222807;11447.027002704863;11069.501872415922;11127.72180695489;11299.604513841976;11313.264637798517;11500.25248358854;8020.398470114648;8111.148329425561;8232.34141268685;8287.088420273885;8345.33237340999;8231.95884020576;10385.324953877975;10716.089132423831;10901.547151890762;11270.94364528172 19:09:57 INFO - PROCESS | 1651 | |3;mootools.html;1256.6983923858277;1297.9356927319595;1335.4991878045732;1341.9932900335498;1343.9193648381095;6430.742770289188;8300.045494768103;8315.667373305061;8320.375971802108;8320.791980200498;7834.490758100719;8075.394345424087;8098.190180981899;8129.715459958903;8246.381521385905;1798.8111248318935;2122.2784253353857;2161.513659426624;2162.9891850540725;2165.7184566006476;2079.9797075150445;2163.8268938484885;2164.101897712447;2207.315732123047;2213.2474958514103;1852.6665200263947;1932.7294178814977;1932.9710054349196;2037.3480486244407;2157.8057974782346;2095.3713885834263;2152.967705484419;2155.493459037125;2161.211157927345;2164.978350216509;2139.016052615783;2164.9242276520245;2168.5012447137087;2170.5441857210003;2172.858764180339;8013.879791803128;8032.3172530335;8120.553369564672;8130.227628375294;8132.878006829843;4859.0767754126955;5019.924701129486;5020.196768517019;5023.824166154167;5027.044861476308;3152.274976755336;3284.4088064148445;3306.8842590509457;3323.285493618859;3377.324535092991;1098.7967226063204;1154.1805318224071;1200.8558972923306;1205.788986927293;1207.963761087169;10010.449425281606;10298.9485052575;10446.68659940203;10616.787664246749;10659.200559957957;4343.847965321199;4433.800478978454;4518.615917646971;4563;4567.429071366079;9245;9320.021397753273;9338.719838404859;9354.766130846647;9363.063693630582;5009.699418034929;5067.822626208065;5077.086124497626;5086.18621020635;5089.109405853961;4502.662300327423;4523.525029871882;4584.7936842842155;4622.19111655459;4651.558101980307;3103.9224019399694;3647.3799454092336;3671.6695497405362;3701.296753616858;3725.8882233533036;1177.140687298277;1207.0946789907568;1210.606552870303;1221.7067903703048;1221.7251118498266;711.7402148215967;743.7991742229676;786.4691333349966;787.933025692811;813.4387272781762;5222.634415590948;5529.640573362799;5539.972300138554;5657.830265092054;5705;828.9046759622721;850.3324889961351;869.3175856952262;886.9689560865339;927.1146055516965;3376.561047063915;3491.965080349164;3676.889693309205;3683.9263214736093;3684.4657524658774;9546.045395460398;9889.06053924876;9896.653617123366;9908.80182396348;9919.107280344804;9552.522373881278;9649.758756031015;9650.662226822027;9826.459544725109;9856.408615483095;7195.604241766753;7347.228541003225;7363.484556081023;7372.778816635509;7382.778516644509;4936.703797772145;4952.8761780955765;5057.494250574913;5064.5948324134715;5278.86802829917;8720.825583488422;8837.381383303107;8847.557622118997;8863.556822158995;8893.510856902933;7239.782806515813;8665.870011949699;8812.206901378906;8961.417507861968;9054.004059553576;6457.128287681103;6505.121808555974;6525.347465253627;6542.313057129123;6629.237637671729;7617.771466856003;7698.923010770045;7707.460477766503;7717.382609391123;7748;6905.413039891666;6982.755603553851;7030.61331626745;7069.752558660628;7175.8564828704175;6796.72813087471;6807.591544507345;6835.077264569419;6879.759208427681;6880.69036893331;5906.40935906406;6104.542159337979;6122;6208.4722798562625;6238.189035425366;4683.578477937002;4764.952350476589;4765.237561990065;4779.5220477951925;4911.165101932752;4780.187368147493;4857;4887.853364399074;4900.068986892479;4931.778069986787 19:09:57 INFO - PROCESS | 1651 | |4;prototype.html;284.76506881822496;289.9695531969142;319.2449856090346;326.72881508348075;332.94339962206436;3768.246350729855;3805.4397696944256;3813.5995720449337;3838.7312888097845;3843.750156239846;3768.5854555998817;3809.752366096202;4025;4042.4542686737354;4049.493813273341;2916.4458752837;3099.9845000774963;3247.431699452598;3289.5394644749813;3298.950515742266;3299.2741596848655;3301.4882693182594;3310.6358300586917;3318.8174650394217;3330.650281720421;2848.5869548915393;3087.4751292280253;3141.4659507883716;3195.5460265579104;3263.396271689745;2698.244491542371;2874.425114976992;3057.617797775278;3241.578594782675;3290.3254832759226;2940.1914473519955;2945.9231241974358;2951.7048295170525;2957.8668959896854;2962.4371369439737;3665.688416484602;3786.9810650946843;3812.1994381179984;3842.289176502356;3870.7290489665734;3145.2923092304277;3461.13471632092;3624.2570273094216;3748.456473811282;3752.774833509971;3498.6851183393615;4248.575142485726;4306.978465107686;4321;4355.891102722426;3286.2770190558276;3315.3203593263565;3320.701136897667;3327.2680010397676;3340.515625234352;3467.427874400721;3670.5778835434;4425.933610995838;4494.853812277914;4554.43069616298;2781.805273630867;3017.7736669749856;3093.777957706693;3335.0828522156603;3351.0449521886144;2796.9720302796713;3240.2709390387445;3399.6090449598614;3421.4183588789965;3426.9314613708084;3118.7349075328398;3442.9686484973486;3754.755940863835;3764.247150569897;3768.962310376916;3546.414841551167;4211.578842115764;4283.843362292164;4295.935560966588;4364.738115713004;1033.4884232305058;1110.8056090184186;1119.8376235445814;1123.4438952718294;1127.2503784983057;686.8145600687786;771.3135309574483;806.7983004248938;828.9544075075808;831.0775039705921;3368.696817286456;3486.773359731609;3526.717862570988;3635.690966267844;3691.077230692327;4137.710360274752;4172.5410204877435;4193.97903010483;4211.115665710236;4222.303319952173;3260.722973411706;3649.288388764219;3662.560492740888;3682.4476328550395;3759.0265110816495;1869.8691091623728;1999.7000449932627;2041.3159143706503;2161.9243326483497;2219.668199080571;3533.6042263306363;3710.5732840723663;4284.293091639907;4323.502797178302;4350.499077818147;3710.4768757245333;3869.7871617061332;4183.305761166733;4331.480222373272;4377.409049778239;3566.928661426809;3783.319002579562;4383.427506582189;4389.714668546598;4425.291953287458;3698.5931547529212;3832.693384529175;3898.044978980168;3967.325603590459;4176.5405805362;3665.4158818033757;3854;4161.583841615814;4191.44321963608;4237.385579091077;3713.6843368312916;4059.8077038398933;4357.9782101089295;4371.956280437155;4391.560843915583;2961.4817406953266;2968.2430980099934;2979.6126503555265;2988.5218365061487;3009.127353067586;3581.409067503833;3990.860319888906;4151.398047283187;4313.094250207491;4379.364992076067;4143.3370660694145;4199.278295898667;4218.957810421856;4225.704200705921;4274.679399045146;4025.879223623179;4263.571703479245;4265.957340426556;4270.160343371747;4291.914161716811;4183.728057676363;4184.991204222051;4205.306124489425;4208.789560522023;4209.6000879916355;3762.7423545872257;4003.5796241394232;4201.327787553977;4279.144171165842;4283.186194623012;3182.777205595586;3313.3870234006786;3511.823539114323;3981.960180398159;4202.768847713282 19:09:57 INFO - PROCESS | 1651 | |5;yui.html;665.2117241069336;683.4668958212593;730.7698075106341;763.4465012865674;776.8329809091044;13596.592102236924;13762.93118534406;14111.871050315951;14149.504767333132;14339.64150896228;13029.153105048188;13106.213627182377;13149.079564430469;13149.408276627551;13157.60527184186;809.5142914251431;833.8248967716787;842.2293601354768;855.6919508976763;865.5412631305418;822.9958850205741;860.4106187261715;860.926821220197;861.4529773593782;861.8233262181269;880.4365206267994;892.2505095719595;893.13366034946;894.4588523942986;894.7136916186823;882.360288790628;882.8013696918206;882.8499155143609;883.337496877342;883.5008220355479;671.2850813883199;671.4997326991374;672.1195234243157;673.0509980926919;673.562184580022;14051.929740351336;14253.643658908506;14397.568072957829;14399.2080435576;14541.05483143603;11406.600768973129;13402.932985335108;13622.250776207306;13878.444862205499;13937.930310348485;9292.721218363393;10865.45672716361;11095.223334366598;11127.888721112766;11409.828852567218;3445.793252404864;3451.4822776583464;3513.982430087833;3541.2740388220354;3605.963940360615;97355;99184.5122323173;100210;100667.49666251695;100828;10024.949875250577;10419.426931518688;10635.627753028606;10755.78488430227;10850.348979061282;17795.288188472576;21309.147634094774;21339.67990480144;21340.573188536142;21385;7145.857082858313;7151.713931442684;7195.280471952868;7240.96379518099;7294.416446684252;3964.623360780721;4000.2799496090984;4002.199560087994;4032.495938007749;4049.5140583130215;3406.7785593936314;3494.790312581202;3509.7367697422796;3518.612952575215;3520.9295814083575;639.1946147853738;641.9325970773095;647.9190101237346;652.2336254900473;653.0498125227782;545.7462280039721;580.1587697838152;587.3715124816491;588.2294194605081;611.8317462697705;9267.67563135287;9616.23070154386;9641.566129524188;9659.227261819038;9989.100980911753;567.7445149682659;587.7267070812093;588.1071661947265;593.7624950020027;599.3736545310161;3496.8251587421037;4609.893625529849;4667.883302917454;4669.5591964014375;4843.830465933675;67579;70051;92428.53785731028;92485;98040.50979745056;65595.34404655988;69676.303236968;75842.24157758462;75914.62042689751;76324.23675763283;46089;57086;68433;71895;72213.99999999895;7834.334081603128;9170.908290917005;9217;9295.86056209171;9381.202597779265;45854.770726146155;46674.76662616665;47091;47161.76419117882;47960.28059579039;60706.69646651739;61816.69091654513;62566.435839104386;62632.686836565525;63974;11184.832227516756;11214.607488737855;11264;11282.887171128512;11321.264117832314;12435.875641243472;12444.81332779991;12681.23912565249;12766.106372554204;12857.03572232105;11453.59912403062;11487.081033517466;11562.364069976233;11583.247088939192;12074.154809163274;8288;10264.794704105727;11462.885371146182;11883.227590206609;11990.760184796429;11343.376114313793;11480.885191147981;11665.35840528779;11905.40472976365;11992.76014479723;9502.382345147544;9666.323357365198;9694;9712.70861874145;9769.364991275545;8091.635876385694;8441.66233350659;8489.575521223791;9188.724338269863;9232.076792320715 19:09:57 INFO - PROCESS | 1651 | __end_tp_report 19:09:57 INFO - PROCESS | 1651 | __start_cc_report 19:09:57 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1731 19:09:57 INFO - PROCESS | 1651 | __end_cc_report 19:09:57 INFO - PROCESS | 1651 | __startTimestamp1486436997453__endTimestamp 19:09:57 INFO - PROCESS | 1651 | 19:09:57 INFO - PROCESS | 1651 | ------- Summary: start ------- 19:09:57 INFO - PROCESS | 1651 | Number of tests: 6 19:09:57 INFO - PROCESS | 1651 | 19:09:57 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:4949.92 Median:5114.16 stddev:570.64 (11.2%) stddev-sans-first:546.45 19:09:57 INFO - PROCESS | 1651 | Values: 2781.7 2811.7 2837.2 2842.8 2864.3 4661.2 4764.1 4867.0 4899.5 4968.6 4749.8 4774.6 4830.2 4868.7 4943.1 4158.8 4424.9 4452.5 4452.9 4634.8 4496.0 4569.5 4592.5 4612.5 4613.2 4303.9 4320.3 4328.3 4383.6 4487.4 4378.1 4529.0 4554.5 4598.2 4614.4 4448.5 4470.7 4492.9 4531.5 4571.6 4645.7 4668.9 4874.0 4883.4 4898.2 5258.5 5276.1 5296.7 5319.0 5329.2 3911.5 5416.7 5505.4 5532.1 5560.2 5072.5 5090.1 5099.9 5100.4 5120.8 5261.9 5324.3 5588.7 5608.9 5646.3 5184.5 5251.3 5292.2 5304.4 5400.6 5429.6 5446.9 5471.3 5493.8 5501.9 4591.4 5252.3 5333.1 5367.7 5396.1 5097.3 5243.9 5252.6 5281.7 5342.4 4433.3 4446.5 4456.7 4470.0 4481.6 3864.3 3870.3 3877.8 3883.7 3898.3 4714.2 4839.8 4840.3 4896.1 4930.8 5401.4 5454.4 5476.2 5588.2 5625.3 4886.8 4940.1 4941.6 4971.9 5004.0 4817.0 4872.7 4874.9 4877.9 4914.5 5309.3 5427.6 5452.9 5541.6 5557.4 5343.7 5408.8 5412.9 5415.2 5419.6 5639.6 5698.7 5708.1 5751.4 5765.4 4472.9 5293.6 5318.8 5330.4 5339.2 4993.6 5098.3 5420.5 5440.0 5443.1 5144.0 5155.6 5213.1 5238.1 5289.6 5186.0 5189.5 5214.2 5214.5 5262.8 4932.6 5114.2 5145.2 5152.0 5163.7 5061.9 5127.6 5168.9 5222.2 5332.7 5177.8 5184.5 5185.8 5195.5 5202.3 19:09:57 INFO - PROCESS | 1651 | 19:09:57 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:58957.29 Median:33064.51 stddev:136543.89 (413.0%) stddev-sans-first:136856.16 19:09:57 INFO - PROCESS | 1651 | Values: 402.3 421.0 427.3 431.1 448.7 28196.4 29284.3 37861.6 39359.6 39366.6 39892.0 40222.0 40284.0 40341.6 40397.6 8770.3 8967.8 9002.4 9005.9 9018.4 7703.2 7923.9 9968.0 10462.1 10752.2 9946.5 10652.9 10659.3 10672.2 10748.6 7599.2 10666.8 10677.4 10747.0 10769.6 7672.7 10206.4 10567.6 10586.7 10746.2 38172.2 38606.4 38625.6 38651.6 38772.0 3821.5 3835.6 3847.9 3857.0 3867.1 28431.7 28507.1 28903.4 29199.0 29421.0 2981.5 2982.5 2984.6 2992.7 3004.1 316247.0 318675.4 319389.4 320156.0 321257.0 47384.5 55444.9 55958.4 56549.7 56583.4 41089.4 44732.0 47053.0 47291.5 47505.0 12492.2 14273.5 14708.9 15032.0 15042.0 11479.4 11563.5 11592.2 11598.3 11608.4 14947.7 14970.3 15033.0 15069.9 15233.2 5928.9 6853.9 6879.7 7052.5 7085.9 710.9 731.4 735.0 751.5 754.9 25203.0 27533.6 28301.3 28440.4 28887.6 815.9 862.9 912.6 927.9 952.0 14127.8 14560.3 14633.7 14972.8 14985.0 567556.0 751364.0 771478.0 772408.0 776014.0 39382.0 42346.1 47004.8 48986.8 49297.3 1280.1 1293.2 1311.6 1312.4 1317.6 22365.8 26002.0 26027.0 26495.7 26761.7 41647.6 41738.7 41749.6 41861.8 41920.8 361632.0 361790.2 362359.0 362931.0 362966.0 20543.1 20846.7 20854.9 21408.9 22461.6 38661.6 39296.0 39779.6 39855.8 41352.8 32287.8 32335.2 32357.0 33217.5 33274.0 30952.0 32228.8 33167.0 33819.8 33877.7 31376.2 31633.9 31944.7 32785.3 32962.0 28037.4 28993.4 29188.6 29447.0 29479.5 18360.4 21547.7 21925.8 22147.6 22448.1 19:09:57 INFO - PROCESS | 1651 | 19:09:57 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:35975.29 Median:24754.69 stddev:106271.09 (429.3%) stddev-sans-first:106535.53 19:09:57 INFO - PROCESS | 1651 | Values: 342.7 349.6 349.9 371.9 384.0 18191.9 18842.0 19439.0 19698.0 19895.7 19863.3 20248.8 20394.1 20639.4 20790.9 4363.8 5524.6 5776.8 5792.7 5801.5 6215.0 6373.7 6419.0 6423.3 6423.9 6008.9 6219.5 6228.3 6263.3 6381.8 6238.3 6286.9 6292.5 6296.2 6303.5 5261.6 5466.7 5521.8 5534.6 5550.0 24501.6 24549.6 24596.0 24645.9 24863.5 2229.8 2238.8 2245.9 2247.6 2286.4 14014.6 14550.9 14663.0 14749.4 15442.5 1709.5 1739.1 1885.2 1894.3 1896.2 110572.0 132488.0 133154.3 136716.0 136857.5 10388.3 10497.6 10879.2 11317.8 11325.6 15320.1 15360.2 15379.7 15393.5 15430.7 7650.0 7939.6 7940.8 8013.6 8065.1 7437.0 7455.8 7493.9 7589.2 7630.5 3790.6 4522.8 4594.1 4707.6 4743.6 1877.3 1957.2 1960.2 1962.8 1969.7 558.6 623.8 696.1 715.3 741.6 19720.6 20589.0 21160.7 21242.7 21311.4 766.9 782.8 793.1 810.1 810.9 10546.0 10710.9 10839.2 10848.7 10906.3 611447.0 619592.0 634184.0 657472.0 673696.0 95733.0 100860.5 107989.5 114672.0 119358.4 784.4 850.3 922.6 923.0 923.0 18782.4 19066.5 19118.7 19149.0 19195.8 54808.7 55877.4 55983.0 56139.2 56173.4 69304.7 75387.6 79207.6 79478.0 82053.2 7860.4 8078.6 8187.1 8225.5 8382.6 10866.8 11109.1 11246.9 12102.7 12619.6 9222.9 10106.9 10638.8 10735.8 11058.5 11011.6 11018.9 11052.8 11358.7 11447.0 11069.5 11127.7 11299.6 11313.3 11500.3 8020.4 8111.1 8232.3 8287.1 8345.3 8232.0 10385.3 10716.1 10901.5 11270.9 19:09:57 INFO - PROCESS | 1651 | 19:09:57 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5221.64 Median:5072.45 stddev:2889.44 (57.0%) stddev-sans-first:2882.18 19:09:57 INFO - PROCESS | 1651 | Values: 1256.7 1297.9 1335.5 1342.0 1343.9 6430.7 8300.0 8315.7 8320.4 8320.8 7834.5 8075.4 8098.2 8129.7 8246.4 1798.8 2122.3 2161.5 2163.0 2165.7 2080.0 2163.8 2164.1 2207.3 2213.2 1852.7 1932.7 1933.0 2037.3 2157.8 2095.4 2153.0 2155.5 2161.2 2165.0 2139.0 2164.9 2168.5 2170.5 2172.9 8013.9 8032.3 8120.6 8130.2 8132.9 4859.1 5019.9 5020.2 5023.8 5027.0 3152.3 3284.4 3306.9 3323.3 3377.3 1098.8 1154.2 1200.9 1205.8 1208.0 10010.4 10298.9 10446.7 10616.8 10659.2 4343.8 4433.8 4518.6 4563.0 4567.4 9245.0 9320.0 9338.7 9354.8 9363.1 5009.7 5067.8 5077.1 5086.2 5089.1 4502.7 4523.5 4584.8 4622.2 4651.6 3103.9 3647.4 3671.7 3701.3 3725.9 1177.1 1207.1 1210.6 1221.7 1221.7 711.7 743.8 786.5 787.9 813.4 5222.6 5529.6 5540.0 5657.8 5705.0 828.9 850.3 869.3 887.0 927.1 3376.6 3492.0 3676.9 3683.9 3684.5 9546.0 9889.1 9896.7 9908.8 9919.1 9552.5 9649.8 9650.7 9826.5 9856.4 7195.6 7347.2 7363.5 7372.8 7382.8 4936.7 4952.9 5057.5 5064.6 5278.9 8720.8 8837.4 8847.6 8863.6 8893.5 7239.8 8665.9 8812.2 8961.4 9054.0 6457.1 6505.1 6525.3 6542.3 6629.2 7617.8 7698.9 7707.5 7717.4 7748.0 6905.4 6982.8 7030.6 7069.8 7175.9 6796.7 6807.6 6835.1 6879.8 6880.7 5906.4 6104.5 6122.0 6208.5 6238.2 4683.6 4765.0 4765.2 4779.5 4911.2 4780.2 4857.0 4887.9 4900.1 4931.8 19:09:57 INFO - PROCESS | 1651 | 19:09:57 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:3437.01 Median:3750.62 stddev:979.64 (26.1%) stddev-sans-first:953.39 19:09:57 INFO - PROCESS | 1651 | Values: 284.8 290.0 319.2 326.7 332.9 3768.2 3805.4 3813.6 3838.7 3843.8 3768.6 3809.8 4025.0 4042.5 4049.5 2916.4 3100.0 3247.4 3289.5 3299.0 3299.3 3301.5 3310.6 3318.8 3330.7 2848.6 3087.5 3141.5 3195.5 3263.4 2698.2 2874.4 3057.6 3241.6 3290.3 2940.2 2945.9 2951.7 2957.9 2962.4 3665.7 3787.0 3812.2 3842.3 3870.7 3145.3 3461.1 3624.3 3748.5 3752.8 3498.7 4248.6 4307.0 4321.0 4355.9 3286.3 3315.3 3320.7 3327.3 3340.5 3467.4 3670.6 4425.9 4494.9 4554.4 2781.8 3017.8 3093.8 3335.1 3351.0 2797.0 3240.3 3399.6 3421.4 3426.9 3118.7 3443.0 3754.8 3764.2 3769.0 3546.4 4211.6 4283.8 4295.9 4364.7 1033.5 1110.8 1119.8 1123.4 1127.3 686.8 771.3 806.8 829.0 831.1 3368.7 3486.8 3526.7 3635.7 3691.1 4137.7 4172.5 4194.0 4211.1 4222.3 3260.7 3649.3 3662.6 3682.4 3759.0 1869.9 1999.7 2041.3 2161.9 2219.7 3533.6 3710.6 4284.3 4323.5 4350.5 3710.5 3869.8 4183.3 4331.5 4377.4 3566.9 3783.3 4383.4 4389.7 4425.3 3698.6 3832.7 3898.0 3967.3 4176.5 3665.4 3854.0 4161.6 4191.4 4237.4 3713.7 4059.8 4358.0 4372.0 4391.6 2961.5 2968.2 2979.6 2988.5 3009.1 3581.4 3990.9 4151.4 4313.1 4379.4 4143.3 4199.3 4219.0 4225.7 4274.7 4025.9 4263.6 4266.0 4270.2 4291.9 4183.7 4185.0 4205.3 4208.8 4209.6 3762.7 4003.6 4201.3 4279.1 4283.2 3182.8 3313.4 3511.8 3982.0 4202.8 19:09:57 INFO - PROCESS | 1651 | 19:09:57 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:18049.81 Median:583.77 stddev:25600.55 (4385.4%) stddev-sans-first:25639.09 19:09:57 INFO - PROCESS | 1651 | Values: 665.2 683.5 730.8 763.4 776.8 13596.6 13762.9 14111.9 14149.5 14339.6 13029.2 13106.2 13149.1 13149.4 13157.6 809.5 833.8 842.2 855.7 865.5 823.0 860.4 860.9 861.5 861.8 880.4 892.3 893.1 894.5 894.7 882.4 882.8 882.8 883.3 883.5 671.3 671.5 672.1 673.1 673.6 14051.9 14253.6 14397.6 14399.2 14541.1 11406.6 13402.9 13622.3 13878.4 13937.9 9292.7 10865.5 11095.2 11127.9 11409.8 3445.8 3451.5 3514.0 3541.3 3606.0 97355.0 99184.5 100210.0 100667.5 100828.0 10024.9 10419.4 10635.6 10755.8 10850.3 17795.3 21309.1 21339.7 21340.6 21385.0 7145.9 7151.7 7195.3 7241.0 7294.4 3964.6 4000.3 4002.2 4032.5 4049.5 3406.8 3494.8 3509.7 3518.6 3520.9 639.2 641.9 647.9 652.2 653.0 545.7 580.2 587.4 588.2 611.8 9267.7 9616.2 9641.6 9659.2 9989.1 567.7 587.7 588.1 593.8 599.4 3496.8 4609.9 4667.9 4669.6 4843.8 67579.0 70051.0 92428.5 92485.0 98040.5 65595.3 69676.3 75842.2 75914.6 76324.2 46089.0 57086.0 68433.0 71895.0 72214.0 7834.3 9170.9 9217.0 9295.9 9381.2 45854.8 46674.8 47091.0 47161.8 47960.3 60706.7 61816.7 62566.4 62632.7 63974.0 11184.8 11214.6 11264.0 11282.9 11321.3 12435.9 12444.8 12681.2 12766.1 12857.0 11453.6 11487.1 11562.4 11583.2 12074.2 8288.0 10264.8 11462.9 11883.2 11990.8 11343.4 11480.9 11665.4 11905.4 11992.8 9502.4 9666.3 9694.0 9712.7 9769.4 8091.6 8441.7 8489.6 9188.7 9232.1 19:09:57 INFO - PROCESS | 1651 | -------- Summary: end -------- 19:09:57 INFO - PROCESS | 1651 | 19:09:58 INFO - TEST-INFO | 1651: exit 0 19:09:58 INFO - TEST-OK | dromaeo_css | took 1118226ms 19:09:58 INFO - TEST-START | kraken 19:09:58 INFO - operating with platform_type : mac_ 19:09:58 INFO - Initialising browser for kraken test... 19:09:58 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpuFuqqv/profile http://localhost:49242/getInfo.html) 19:10:00 INFO - PROCESS | 1658 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 19:10:00 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 19:10:00 INFO - PROCESS | 1658 | colorDepth:24 19:10:00 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 19:10:00 INFO - PROCESS | 1658 | __metrics 19:10:00 INFO - PROCESS | 1658 | 1486437000625 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 19:10:00 INFO - PROCESS | 1658 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:10:00 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:10:01 INFO - TEST-INFO | 1658: exit 0 19:10:01 INFO - Browser initialized. 19:10:01 INFO - Running cycle 1/1 for kraken test... 19:10:01 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpuFuqqv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:10:13 INFO - PROCESS | 1659 | RSS: Main: 236806144 19:10:13 INFO - PROCESS | 1659 | 19:10:16 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 19:10:17 INFO - PROCESS | 1659 | RSS: Main: 287797248 19:10:17 INFO - PROCESS | 1659 | 19:10:20 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 19:10:21 INFO - PROCESS | 1659 | RSS: Main: 289468416 19:10:21 INFO - PROCESS | 1659 | 19:10:24 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 19:10:25 INFO - PROCESS | 1659 | RSS: Main: 383324160 19:10:25 INFO - PROCESS | 1659 | 19:10:27 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 19:10:27 INFO - PROCESS | 1659 | RSS: Main: 285257728 19:10:27 INFO - PROCESS | 1659 | 19:10:30 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:10:30 INFO - PROCESS | 1659 | RSS: Main: 286732288 19:10:30 INFO - PROCESS | 1659 | 19:10:33 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 19:10:34 INFO - PROCESS | 1659 | RSS: Main: 327319552 19:10:34 INFO - PROCESS | 1659 | 19:10:37 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 19:10:38 INFO - PROCESS | 1659 | RSS: Main: 332726272 19:10:38 INFO - PROCESS | 1659 | 19:10:41 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 19:10:41 INFO - PROCESS | 1659 | RSS: Main: 329654272 19:10:41 INFO - PROCESS | 1659 | 19:10:43 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:10:44 INFO - PROCESS | 1659 | RSS: Main: 305532928 19:10:44 INFO - PROCESS | 1659 | 19:10:46 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:10:46 INFO - PROCESS | 1659 | RSS: Main: 309854208 19:10:46 INFO - PROCESS | 1659 | 19:10:49 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:10:49 INFO - PROCESS | 1659 | RSS: Main: 311853056 19:10:49 INFO - PROCESS | 1659 | 19:10:52 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:10:53 INFO - PROCESS | 1659 | RSS: Main: 330579968 19:10:53 INFO - PROCESS | 1659 | 19:10:57 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:10:57 INFO - PROCESS | 1659 | RSS: Main: 323727360 19:10:57 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 19:10:59 INFO - PROCESS | 1659 | RSS: Main: 369651712 19:10:59 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | __start_tp_report 19:10:59 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 19:10:59 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 19:10:59 INFO - PROCESS | 1659 | |i|pagename|runs| 19:10:59 INFO - PROCESS | 1659 | |0;ai-astar;119;118;118;117;118;127;176;157;118;118 19:10:59 INFO - PROCESS | 1659 | |1;audio-beat-detection;175;148;114;157;139;138;144;146;134;112 19:10:59 INFO - PROCESS | 1659 | |2;audio-dft;213;214;215;212;209;213;215;213;215;213 19:10:59 INFO - PROCESS | 1659 | |3;audio-fft;81;61;63;90;63;62;59;61;77;59 19:10:59 INFO - PROCESS | 1659 | |4;audio-oscillator;88;73;116;107;103;72;89;94;106;119 19:10:59 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;91;89;91;87;91;87;91;88;91;87 19:10:59 INFO - PROCESS | 1659 | |6;imaging-darkroom;101;101;101;102;102;102;101;101;102;121 19:10:59 INFO - PROCESS | 1659 | |7;imaging-desaturate;105;103;102;107;101;103;107;108;107;105 19:10:59 INFO - PROCESS | 1659 | |8;json-parse-financial;60;60;61;57;62;62;63;62;60;61 19:10:59 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;68;80;84;77;77;74;74;75;76;75 19:10:59 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;91;97;114;117;97;96;92;93;95;96 19:10:59 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;149;147;146;150;153;162;166;148;212;177 19:10:59 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;186;185;184;178;181;182;179;183;245;212 19:10:59 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;71;71;71;71;71;70;77;65;64;72 19:10:59 INFO - PROCESS | 1659 | __end_tp_report 19:10:59 INFO - PROCESS | 1659 | __start_cc_report 19:10:59 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2259 19:10:59 INFO - PROCESS | 1659 | __end_cc_report 19:10:59 INFO - PROCESS | 1659 | __startTimestamp1486437059722__endTimestamp 19:10:59 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | ------- Summary: start ------- 19:10:59 INFO - PROCESS | 1659 | Number of tests: 14 19:10:59 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:128.60 Median:118.50 stddev:20.67 (17.4%) stddev-sans-first:21.63 19:10:59 INFO - PROCESS | 1659 | Values: 119.0 118.0 118.0 117.0 118.0 127.0 176.0 157.0 118.0 118.0 19:10:59 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:140.70 Median:145.00 stddev:18.64 (12.9%) stddev-sans-first:15.08 19:10:59 INFO - PROCESS | 1659 | Values: 175.0 148.0 114.0 157.0 139.0 138.0 144.0 146.0 134.0 112.0 19:10:59 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:213.20 Median:213.50 stddev:1.81 (0.8%) stddev-sans-first:1.92 19:10:59 INFO - PROCESS | 1659 | Values: 213.0 214.0 215.0 212.0 209.0 213.0 215.0 213.0 215.0 213.0 19:10:59 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:67.60 Median:63.00 stddev:10.95 (17.4%) stddev-sans-first:10.48 19:10:59 INFO - PROCESS | 1659 | Values: 81.0 61.0 63.0 90.0 63.0 62.0 59.0 61.0 77.0 59.0 19:10:59 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:72.50 stddev:16.38 (22.6%) stddev-sans-first:17.07 19:10:59 INFO - PROCESS | 1659 | Values: 88.0 73.0 116.0 107.0 103.0 72.0 89.0 94.0 106.0 119.0 19:10:59 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:91.00 stddev:1.89 (2.1%) stddev-sans-first:1.90 19:10:59 INFO - PROCESS | 1659 | Values: 91.0 89.0 91.0 87.0 91.0 87.0 91.0 88.0 91.0 87.0 19:10:59 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:103.40 Median:102.00 stddev:6.20 (6.1%) stddev-sans-first:6.52 19:10:59 INFO - PROCESS | 1659 | Values: 101.0 101.0 101.0 102.0 102.0 102.0 101.0 101.0 102.0 121.0 19:10:59 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:104.80 Median:106.00 stddev:2.44 (2.3%) stddev-sans-first:2.59 19:10:59 INFO - PROCESS | 1659 | Values: 105.0 103.0 102.0 107.0 101.0 103.0 107.0 108.0 107.0 105.0 19:10:59 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:60.80 Median:61.50 stddev:1.69 (2.7%) stddev-sans-first:1.76 19:10:59 INFO - PROCESS | 1659 | Values: 60.0 60.0 61.0 57.0 62.0 62.0 63.0 62.0 60.0 61.0 19:10:59 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:76.00 Median:76.50 stddev:4.16 (5.4%) stddev-sans-first:3.26 19:10:59 INFO - PROCESS | 1659 | Values: 68.0 80.0 84.0 77.0 77.0 74.0 74.0 75.0 76.0 75.0 19:10:59 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:98.80 Median:95.50 stddev:9.07 (9.5%) stddev-sans-first:9.17 19:10:59 INFO - PROCESS | 1659 | Values: 91.0 97.0 114.0 117.0 97.0 96.0 92.0 93.0 95.0 96.0 19:10:59 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:161.00 Median:157.50 stddev:20.55 (13.0%) stddev-sans-first:21.34 19:10:59 INFO - PROCESS | 1659 | Values: 149.0 147.0 146.0 150.0 153.0 162.0 166.0 148.0 212.0 177.0 19:10:59 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.50 Median:184.50 stddev:21.14 (11.5%) stddev-sans-first:22.33 19:10:59 INFO - PROCESS | 1659 | Values: 186.0 185.0 184.0 178.0 181.0 182.0 179.0 183.0 245.0 212.0 19:10:59 INFO - PROCESS | 1659 | 19:10:59 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:71.00 stddev:3.62 (5.1%) stddev-sans-first:3.83 19:10:59 INFO - PROCESS | 1659 | Values: 71.0 71.0 71.0 71.0 71.0 70.0 77.0 65.0 64.0 72.0 19:10:59 INFO - PROCESS | 1659 | -------- Summary: end -------- 19:10:59 INFO - PROCESS | 1659 | 19:11:00 INFO - TEST-INFO | 1659: exit 0 19:11:00 INFO - TEST-OK | kraken | took 62566ms 19:11:00 INFO - SUITE-END | took 1180s 19:11:00 INFO - Completed test suite (00:19:40) 19:11:00 INFO - javascript benchmark 19:11:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6482.564816086508, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2781.6940136584976, 2811.74694277515, 2837.24812924575, 2842.8294302341874, 2864.312564984404, 4661.184292748772, 4764.094821983829, 4866.975665121674, 4899.461059283481, 4968.627352948525, 4749.833755818547, 4774.618030557555, 4830.227163653816, 4868.732219727914, 4943.1102401567705, 4158.812853421603, 4424.867253982386, 4452.487963884146, 4452.933206001912, 4634.814607415717, 4496.043481736067, 4569.520200378962, 4592.494825569184, 4612.515685852987, 4613.1926912790295, 4303.935440968388, 4320.287152619814, 4328.307470804656, 4383.605475507188, 4487.394201782767, 4378.124375124956, 4528.977355113237, 4554.476235232957, 4598.241290187115, 4614.423197100363, 4448.488423831236, 4470.731756094645, 4492.932606010912, 4531.501534831166, 4571.634269258451, 4645.65157613177, 4668.85993420198, 4873.951260487385, 4883.413990321184, 4898.21628539432, 5258.50044245796, 5276.12943864262, 5296.682199068068, 5318.973405132988, 5329.173978033411, 3911.4915061041884, 5416.729163541807, 5505.366882808488, 5532.1425179097305, 5560.2493663355335, 5072.467390923936, 5090.10923088458, 5099.923501147448, 5100.438951715382, 5120.820771272987, 5261.868453288622, 5324.334458192726, 5588.692621905834, 5608.915866262009, 5646.265985421951, 5184.48155184486, 5251.291075704808, 5292.153255479105, 5304.363476382859, 5400.594955378362, 5429.592780541475, 5446.945530544723, 5471.261379713767, 5493.7802487900835, 5501.862453438696, 4591.403117594691, 5252.264682944391, 5333.146696528537, 5367.731613419313, 5396.109641909119, 5097.311862898536, 5243.868903277448, 5252.606054545848, 5281.656692314988, 5342.412334643186, 4433.312836510346, 4446.5331140230455, 4456.732596044248, 4470.0, 4481.619062379734, 3864.304425203434, 3870.3420418528917, 3877.7867217302755, 3883.7087218458164, 3898.278818418545, 4714.222153344728, 4839.782209800567, 4840.273958906122, 4896.069746748107, 4930.778114984834, 5401.351837779492, 5454.400015998237, 5476.1511965644595, 5588.189712491747, 5625.324961004624, 4886.780094895738, 4940.135476291634, 4941.604671626261, 4971.875703107451, 5003.974980125076, 4816.975915120402, 4872.731999740048, 4874.926876096862, 4877.878053048631, 4914.483979182206, 5309.309789727388, 5427.620066595379, 5452.863678408072, 5541.639793413416, 5557.41647127062, 5343.65266257692, 5408.78364865401, 5412.945870541244, 5415.160650099241, 5419.647722897999, 5639.605227634026, 5698.6580805151825, 5708.143778433268, 5751.3673495916255, 5765.423457654201, 4472.865814025585, 5293.550048245942, 5318.787248510016, 5330.413654498079, 5339.172428273624, 4993.600511958962, 5098.2862399263395, 5420.457954204548, 5440.047991601375, 5443.101888188405, 5144.0, 5155.613329000415, 5213.113770658922, 5238.135707608202, 5289.6032797539565, 5186.0, 5189.455107213688, 5214.165733482625, 5214.452482489436, 5262.763175657181, 4932.580730637793, 5114.15616423286, 5145.202493613496, 5152.0, 5163.715995620277, 5061.924071138862, 5127.5897928166405, 5168.896622067613, 5222.242774797557, 5332.73336333174, 5177.767000485062, 5184.455632158569, 5185.792568297225, 5195.532402083831, 5202.271681964604], "value": 4913.555447282543, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.2879503279196, 421.012725159501, 427.2672366890783, 431.1334218221376, 448.70833957927323, 28196.43607127859, 29284.267893302676, 37861.62138378612, 39359.606403935955, 39366.60633393665, 39892.0, 40222.0, 40284.0, 40341.59658403415, 40397.596024039754, 8770.298376129911, 8967.775805604846, 9002.369834111581, 9005.864912026325, 9018.368714189977, 7703.191164927686, 7923.881141782877, 9968.0, 10462.110720588758, 10752.193585481082, 9946.502674866228, 10652.946735266312, 10659.307145035587, 10672.199585031114, 10748.570057197703, 7599.240075992412, 10666.839997400046, 10677.41274229917, 10747.032767050925, 10769.569217231341, 7672.693092276302, 10206.438645874474, 10567.630132945387, 10586.735331616694, 10746.247762656618, 38172.23655526902, 38606.42090368647, 38625.61374386276, 38651.61348386508, 38772.0, 3821.5414150301863, 3835.5589107252463, 3847.8653247136217, 3856.981423841717, 3867.1298957734566, 28431.71568284332, 28507.144785656463, 28903.42193156146, 29199.0, 29421.0, 2981.4931461651354, 2982.5078861987963, 2984.5523171524446, 2992.7456166225898, 3004.1438190115714, 316247.0, 318675.4066229654, 319389.40305298555, 320156.0, 321257.0, 47384.5261547387, 55444.89110217854, 55958.44041559614, 56549.71725141348, 56583.43416565782, 41089.38365924513, 44732.0, 47053.0, 47291.5270847294, 47505.0, 12492.188007779465, 14273.500427484989, 14708.926455367655, 15032.0, 15042.0, 11479.368634725004, 11563.479643416067, 11592.246503977214, 11598.304101753922, 11608.419579021183, 14947.70104597902, 14970.251487425585, 15033.0, 15069.924650376677, 15233.238338083273, 5928.940710592925, 6853.897191542131, 6879.6560171991205, 7052.506324557232, 7085.89371159433, 710.9123041746137, 731.4294850016995, 734.9963250183715, 751.4664588142371, 754.9056367954006, 25203.0, 27533.586996195074, 28301.31681251119, 28440.431191376058, 28887.56668649972, 815.8531464336357, 862.8878245828002, 912.5528491039342, 927.8561822917459, 951.9857202141974, 14127.78808317876, 14560.271986400638, 14633.707325853424, 14972.775408368883, 14985.0259733117, 567556.0, 751364.0, 771478.0, 772408.0, 776014.0, 39382.0, 42346.0944257503, 47004.7649761749, 48986.75506622444, 49297.26054109191, 1280.065552146938, 1293.1529847949607, 1311.613074143111, 1312.3962977030292, 1317.6376496463358, 22365.776342236368, 26002.0, 26027.0, 26495.73504264933, 26761.732382676702, 41647.58352416437, 41738.74783756492, 41749.58250417457, 41861.79069104635, 41920.79039604782, 361632.0, 361790.1910490431, 362359.0, 362931.0, 362966.0, 20543.075561600064, 20846.68729969082, 20854.89572552188, 21408.92955352195, 22461.5507689842, 38661.6133838658, 39296.0, 39779.60220397875, 39855.80072099621, 41352.79323603483, 32287.838560807046, 32335.191620209684, 32357.0, 33217.50173747444, 33274.0, 30952.0, 32228.83885580557, 33167.0, 33819.830900845336, 33877.66122338844, 31376.215594610316, 31633.89281375141, 31944.68055319417, 32785.34429311353, 32962.01113966585, 28037.439251214455, 28993.420131596828, 29188.56217156702, 29447.0, 29479.458055010837, 18360.357387491375, 21547.707137571797, 21925.780742192375, 22147.55704885861, 22448.10207591678], "value": 17625.441744834574, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.66761241595657, 349.58574089703706, 349.8565588108876, 371.8624109079641, 383.9616038396161, 18191.909040454797, 18842.0, 19439.0, 19698.0, 19895.701564476545, 19863.30478433255, 20248.785072895636, 20394.082266298014, 20639.380818575428, 20790.89604551979, 4363.825446982117, 5524.558035357172, 5776.82669519913, 5792.68140252286, 5801.47786699197, 6214.968925155391, 6373.7450501979865, 6419.037144428326, 6423.261324947644, 6423.93576064238, 6008.879822403549, 6219.533534984872, 6228.314885362606, 6263.342349053352, 6381.7766378176775, 6238.344973777756, 6286.87426251477, 6292.528060395489, 6296.181496405484, 6303.464205542535, 5261.552768014723, 5466.671999680032, 5521.834344969658, 5534.612577119603, 5549.972250138764, 24501.63247551288, 24549.631755523682, 24596.0, 24645.876770616032, 24863.50272994548, 2229.7547269800307, 2238.753737088919, 2245.921392751246, 2247.5954328220914, 2286.371247906822, 14014.579562613037, 14550.854491455057, 14662.973591848468, 14749.410023599043, 15442.536723898302, 1709.529879283207, 1739.0611856873334, 1885.198790514026, 1894.2991093295568, 1896.2035944903173, 110572.0, 132488.0, 133154.33422832823, 136716.0, 136857.5227697635, 10388.272820902615, 10497.580096796042, 10879.184061195441, 11317.773644527064, 11325.603603873826, 15320.080795152326, 15360.23198840076, 15379.692406151815, 15393.538193854203, 15430.691386172213, 7650.0, 7939.563324017235, 7940.761777146695, 8013.599320033975, 8065.072516660542, 7436.962815185998, 7455.776326710207, 7493.850122997618, 7589.165191828894, 7630.465867389229, 3790.5830358660523, 4522.819087236539, 4594.1271158479785, 4707.599854012379, 4743.644226682944, 1877.296013994752, 1957.1779852462, 1960.2061165228106, 1962.823345898876, 1969.7439332886634, 558.5531574740191, 623.7723231020644, 696.098552374676, 715.2811424518326, 741.6180666956521, 19720.605587888163, 20589.0, 21160.682589761167, 21242.681359779617, 21311.36065917994, 766.9079710434784, 782.8160382310152, 793.1434051224663, 810.13720387788, 810.8864758933754, 10546.0, 10710.94573890205, 10839.241253112206, 10848.651777338708, 10906.27343164209, 611447.0, 619592.0, 634184.0, 657472.0, 673696.0, 95733.0, 100860.4956975225, 107989.4600527008, 114672.0, 119358.40320798515, 784.4352066512103, 850.2815121222557, 922.6171138977384, 922.9953850230706, 923.0262073512508, 18782.436526904214, 19066.52333691669, 19118.713219301444, 19149.042547872272, 19195.80804191996, 54808.72595636996, 55877.44122558722, 55983.0, 56139.15791263216, 56173.43826561682, 69304.65347673229, 75387.62306188434, 79207.60396198212, 79478.0, 82053.17946820694, 7860.4104692147175, 8078.636461359135, 8187.099419063779, 8225.465344752572, 8382.580870956306, 10866.836997444887, 11109.111271098454, 11246.943765281121, 12102.697432564257, 12619.621411357673, 9222.861657075015, 10106.898931010595, 10638.787224255626, 10735.785284294427, 11058.502367393326, 11011.559537618727, 11018.889811102106, 11052.834207486734, 11358.659240222807, 11447.027002704863, 11069.501872415922, 11127.72180695489, 11299.604513841976, 11313.264637798517, 11500.25248358854, 8020.398470114648, 8111.148329425561, 8232.34141268685, 8287.088420273885, 8345.33237340999, 8231.95884020576, 10385.324953877975, 10716.089132423831, 10901.547151890762, 11270.94364528172], "value": 9400.223989072563, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1256.6983923858277, 1297.9356927319595, 1335.4991878045732, 1341.9932900335498, 1343.9193648381095, 6430.742770289188, 8300.045494768103, 8315.667373305061, 8320.375971802108, 8320.791980200498, 7834.490758100719, 8075.394345424087, 8098.190180981899, 8129.715459958903, 8246.381521385905, 1798.8111248318935, 2122.2784253353857, 2161.513659426624, 2162.9891850540725, 2165.7184566006476, 2079.9797075150445, 2163.8268938484885, 2164.101897712447, 2207.315732123047, 2213.2474958514103, 1852.6665200263947, 1932.7294178814977, 1932.9710054349196, 2037.3480486244407, 2157.8057974782346, 2095.3713885834263, 2152.967705484419, 2155.493459037125, 2161.211157927345, 2164.978350216509, 2139.016052615783, 2164.9242276520245, 2168.5012447137087, 2170.5441857210003, 2172.858764180339, 8013.879791803128, 8032.3172530335, 8120.553369564672, 8130.227628375294, 8132.878006829843, 4859.0767754126955, 5019.924701129486, 5020.196768517019, 5023.824166154167, 5027.044861476308, 3152.274976755336, 3284.4088064148445, 3306.8842590509457, 3323.285493618859, 3377.324535092991, 1098.7967226063204, 1154.1805318224071, 1200.8558972923306, 1205.788986927293, 1207.963761087169, 10010.449425281606, 10298.9485052575, 10446.68659940203, 10616.787664246749, 10659.200559957957, 4343.847965321199, 4433.800478978454, 4518.615917646971, 4563.0, 4567.429071366079, 9245.0, 9320.021397753273, 9338.719838404859, 9354.766130846647, 9363.063693630582, 5009.699418034929, 5067.822626208065, 5077.086124497626, 5086.18621020635, 5089.109405853961, 4502.662300327423, 4523.525029871882, 4584.7936842842155, 4622.19111655459, 4651.558101980307, 3103.9224019399694, 3647.3799454092336, 3671.6695497405362, 3701.296753616858, 3725.8882233533036, 1177.140687298277, 1207.0946789907568, 1210.606552870303, 1221.7067903703048, 1221.7251118498266, 711.7402148215967, 743.7991742229676, 786.4691333349966, 787.933025692811, 813.4387272781762, 5222.634415590948, 5529.640573362799, 5539.972300138554, 5657.830265092054, 5705.0, 828.9046759622721, 850.3324889961351, 869.3175856952262, 886.9689560865339, 927.1146055516965, 3376.561047063915, 3491.965080349164, 3676.889693309205, 3683.9263214736093, 3684.4657524658774, 9546.045395460398, 9889.06053924876, 9896.653617123366, 9908.80182396348, 9919.107280344804, 9552.522373881278, 9649.758756031015, 9650.662226822027, 9826.459544725109, 9856.408615483095, 7195.604241766753, 7347.228541003225, 7363.484556081023, 7372.778816635509, 7382.778516644509, 4936.703797772145, 4952.8761780955765, 5057.494250574913, 5064.5948324134715, 5278.86802829917, 8720.825583488422, 8837.381383303107, 8847.557622118997, 8863.556822158995, 8893.510856902933, 7239.782806515813, 8665.870011949699, 8812.206901378906, 8961.417507861968, 9054.004059553576, 6457.128287681103, 6505.121808555974, 6525.347465253627, 6542.313057129123, 6629.237637671729, 7617.771466856003, 7698.923010770045, 7707.460477766503, 7717.382609391123, 7748.0, 6905.413039891666, 6982.755603553851, 7030.61331626745, 7069.752558660628, 7175.8564828704175, 6796.72813087471, 6807.591544507345, 6835.077264569419, 6879.759208427681, 6880.69036893331, 5906.40935906406, 6104.542159337979, 6122.0, 6208.4722798562625, 6238.189035425366, 4683.578477937002, 4764.952350476589, 4765.237561990065, 4779.5220477951925, 4911.165101932752, 4780.187368147493, 4857.0, 4887.853364399074, 4900.068986892479, 4931.778069986787], "value": 4207.243069387375, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.76506881822496, 289.9695531969142, 319.2449856090346, 326.72881508348075, 332.94339962206436, 3768.246350729855, 3805.4397696944256, 3813.5995720449337, 3838.7312888097845, 3843.750156239846, 3768.5854555998817, 3809.752366096202, 4025.0, 4042.4542686737354, 4049.493813273341, 2916.4458752837, 3099.9845000774963, 3247.431699452598, 3289.5394644749813, 3298.950515742266, 3299.2741596848655, 3301.4882693182594, 3310.6358300586917, 3318.8174650394217, 3330.650281720421, 2848.5869548915393, 3087.4751292280253, 3141.4659507883716, 3195.5460265579104, 3263.396271689745, 2698.244491542371, 2874.425114976992, 3057.617797775278, 3241.578594782675, 3290.3254832759226, 2940.1914473519955, 2945.9231241974358, 2951.7048295170525, 2957.8668959896854, 2962.4371369439737, 3665.688416484602, 3786.9810650946843, 3812.1994381179984, 3842.289176502356, 3870.7290489665734, 3145.2923092304277, 3461.13471632092, 3624.2570273094216, 3748.456473811282, 3752.774833509971, 3498.6851183393615, 4248.575142485726, 4306.978465107686, 4321.0, 4355.891102722426, 3286.2770190558276, 3315.3203593263565, 3320.701136897667, 3327.2680010397676, 3340.515625234352, 3467.427874400721, 3670.5778835434, 4425.933610995838, 4494.853812277914, 4554.43069616298, 2781.805273630867, 3017.7736669749856, 3093.777957706693, 3335.0828522156603, 3351.0449521886144, 2796.9720302796713, 3240.2709390387445, 3399.6090449598614, 3421.4183588789965, 3426.9314613708084, 3118.7349075328398, 3442.9686484973486, 3754.755940863835, 3764.247150569897, 3768.962310376916, 3546.414841551167, 4211.578842115764, 4283.843362292164, 4295.935560966588, 4364.738115713004, 1033.4884232305058, 1110.8056090184186, 1119.8376235445814, 1123.4438952718294, 1127.2503784983057, 686.8145600687786, 771.3135309574483, 806.7983004248938, 828.9544075075808, 831.0775039705921, 3368.696817286456, 3486.773359731609, 3526.717862570988, 3635.690966267844, 3691.077230692327, 4137.710360274752, 4172.5410204877435, 4193.97903010483, 4211.115665710236, 4222.303319952173, 3260.722973411706, 3649.288388764219, 3662.560492740888, 3682.4476328550395, 3759.0265110816495, 1869.8691091623728, 1999.7000449932627, 2041.3159143706503, 2161.9243326483497, 2219.668199080571, 3533.6042263306363, 3710.5732840723663, 4284.293091639907, 4323.502797178302, 4350.499077818147, 3710.4768757245333, 3869.7871617061332, 4183.305761166733, 4331.480222373272, 4377.409049778239, 3566.928661426809, 3783.319002579562, 4383.427506582189, 4389.714668546598, 4425.291953287458, 3698.5931547529212, 3832.693384529175, 3898.044978980168, 3967.325603590459, 4176.5405805362, 3665.4158818033757, 3854.0, 4161.583841615814, 4191.44321963608, 4237.385579091077, 3713.6843368312916, 4059.8077038398933, 4357.9782101089295, 4371.956280437155, 4391.560843915583, 2961.4817406953266, 2968.2430980099934, 2979.6126503555265, 2988.5218365061487, 3009.127353067586, 3581.409067503833, 3990.860319888906, 4151.398047283187, 4313.094250207491, 4379.364992076067, 4143.3370660694145, 4199.278295898667, 4218.957810421856, 4225.704200705921, 4274.679399045146, 4025.879223623179, 4263.571703479245, 4265.957340426556, 4270.160343371747, 4291.914161716811, 4183.728057676363, 4184.991204222051, 4205.306124489425, 4208.789560522023, 4209.6000879916355, 3762.7423545872257, 4003.5796241394232, 4201.327787553977, 4279.144171165842, 4283.186194623012, 3182.777205595586, 3313.3870234006786, 3511.823539114323, 3981.960180398159, 4202.768847713282], "value": 3153.672346117753, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.2117241069336, 683.4668958212593, 730.7698075106341, 763.4465012865674, 776.8329809091044, 13596.592102236924, 13762.93118534406, 14111.871050315951, 14149.504767333132, 14339.64150896228, 13029.153105048188, 13106.213627182377, 13149.079564430469, 13149.408276627551, 13157.60527184186, 809.5142914251431, 833.8248967716787, 842.2293601354768, 855.6919508976763, 865.5412631305418, 822.9958850205741, 860.4106187261715, 860.926821220197, 861.4529773593782, 861.8233262181269, 880.4365206267994, 892.2505095719595, 893.13366034946, 894.4588523942986, 894.7136916186823, 882.360288790628, 882.8013696918206, 882.8499155143609, 883.337496877342, 883.5008220355479, 671.2850813883199, 671.4997326991374, 672.1195234243157, 673.0509980926919, 673.562184580022, 14051.929740351336, 14253.643658908506, 14397.568072957829, 14399.2080435576, 14541.05483143603, 11406.600768973129, 13402.932985335108, 13622.250776207306, 13878.444862205499, 13937.930310348485, 9292.721218363393, 10865.45672716361, 11095.223334366598, 11127.888721112766, 11409.828852567218, 3445.793252404864, 3451.4822776583464, 3513.982430087833, 3541.2740388220354, 3605.963940360615, 97355.0, 99184.5122323173, 100210.0, 100667.49666251695, 100828.0, 10024.949875250577, 10419.426931518688, 10635.627753028606, 10755.78488430227, 10850.348979061282, 17795.288188472576, 21309.147634094774, 21339.67990480144, 21340.573188536142, 21385.0, 7145.857082858313, 7151.713931442684, 7195.280471952868, 7240.96379518099, 7294.416446684252, 3964.623360780721, 4000.2799496090984, 4002.199560087994, 4032.495938007749, 4049.5140583130215, 3406.7785593936314, 3494.790312581202, 3509.7367697422796, 3518.612952575215, 3520.9295814083575, 639.1946147853738, 641.9325970773095, 647.9190101237346, 652.2336254900473, 653.0498125227782, 545.7462280039721, 580.1587697838152, 587.3715124816491, 588.2294194605081, 611.8317462697705, 9267.67563135287, 9616.23070154386, 9641.566129524188, 9659.227261819038, 9989.100980911753, 567.7445149682659, 587.7267070812093, 588.1071661947265, 593.7624950020027, 599.3736545310161, 3496.8251587421037, 4609.893625529849, 4667.883302917454, 4669.5591964014375, 4843.830465933675, 67579.0, 70051.0, 92428.53785731028, 92485.0, 98040.50979745056, 65595.34404655988, 69676.303236968, 75842.24157758462, 75914.62042689751, 76324.23675763283, 46089.0, 57086.0, 68433.0, 71895.0, 72213.99999999895, 7834.334081603128, 9170.908290917005, 9217.0, 9295.86056209171, 9381.202597779265, 45854.770726146155, 46674.76662616665, 47091.0, 47161.76419117882, 47960.28059579039, 60706.69646651739, 61816.69091654513, 62566.435839104386, 62632.686836565525, 63974.0, 11184.832227516756, 11214.607488737855, 11264.0, 11282.887171128512, 11321.264117832314, 12435.875641243472, 12444.81332779991, 12681.23912565249, 12766.106372554204, 12857.03572232105, 11453.59912403062, 11487.081033517466, 11562.364069976233, 11583.247088939192, 12074.154809163274, 8288.0, 10264.794704105727, 11462.885371146182, 11883.227590206609, 11990.760184796429, 11343.376114313793, 11480.885191147981, 11665.35840528779, 11905.40472976365, 11992.76014479723, 9502.382345147544, 9666.323357365198, 9694.0, 9712.70861874145, 9769.364991275545, 8091.635876385694, 8441.66233350659, 8489.575521223791, 9188.724338269863, 9232.076792320715], "value": 6869.601756134974, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1602.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 118.0, 117.0, 118.0, 127.0, 176.0, 157.0, 118.0, 118.0], "value": 128.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 148.0, 114.0, 157.0, 139.0, 138.0, 144.0, 146.0, 134.0, 112.0], "value": 140.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [213.0, 214.0, 215.0, 212.0, 209.0, 213.0, 215.0, 213.0, 215.0, 213.0], "value": 213.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 63.0, 90.0, 63.0, 62.0, 59.0, 61.0, 77.0, 59.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 73.0, 116.0, 107.0, 103.0, 72.0, 89.0, 94.0, 106.0, 119.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 91.0, 87.0, 91.0, 87.0, 91.0, 88.0, 91.0, 87.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 101.0, 101.0, 102.0, 102.0, 102.0, 101.0, 101.0, 102.0, 121.0], "value": 103.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 103.0, 102.0, 107.0, 101.0, 103.0, 107.0, 108.0, 107.0, 105.0], "value": 104.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 61.0, 57.0, 62.0, 62.0, 63.0, 62.0, 60.0, 61.0], "value": 60.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 80.0, 84.0, 77.0, 77.0, 74.0, 74.0, 75.0, 76.0, 75.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 97.0, 114.0, 117.0, 97.0, 96.0, 92.0, 93.0, 95.0, 96.0], "value": 98.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 146.0, 150.0, 153.0, 162.0, 166.0, 148.0, 212.0, 177.0], "value": 161.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 184.0, 178.0, 181.0, 182.0, 179.0, 183.0, 245.0, 212.0], "value": 191.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 71.0, 71.0, 71.0, 70.0, 77.0, 65.0, 64.0, 72.0], "value": 70.3, "unit": "score"}]}]} 19:11:00 INFO - Return code: 0 19:11:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:11:00 ERROR - # TBPL SUCCESS # 19:11:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:11:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:11:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:11:00 INFO - 2017-02-06 19:11:00.995 screenresolution[1663:10552] starting screenresolution argv=screenresolution get 19:11:00 INFO - 2017-02-06 19:11:00.998 screenresolution[1663:10552] Display 0: 1600x1200x32@60 19:11:01 INFO - 2017-02-06 19:11:01.004 screenresolution[1664:10555] starting screenresolution argv=screenresolution list 19:11:01 INFO - Available Modes on Display 0 19:11:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:11:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:11:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:11:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:11:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:11:01 INFO - Intel Iris: 19:11:01 INFO - Chipset Model: Intel Iris 19:11:01 INFO - Type: GPU 19:11:01 INFO - Bus: Built-In 19:11:01 INFO - VRAM (Dynamic, Max): 1536 MB 19:11:01 INFO - Vendor: Intel (0x8086) 19:11:01 INFO - Device ID: 0x0a2e 19:11:01 INFO - Revision ID: 0x0009 19:11:01 INFO - Displays: 19:11:01 INFO - Display: 19:11:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:11:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:11:01 INFO - Display Serial Number: 1600x1200 60 19:11:01 INFO - Main Display: Yes 19:11:01 INFO - Mirror: Off 19:11:01 INFO - Online: Yes 19:11:01 INFO - Rotation: Supported 19:11:01 INFO - Return code: 0 19:11:01 INFO - Running post-action listener: _resource_record_post_action 19:11:01 INFO - [mozharness: 2017-02-07 03:11:01.262900Z] Finished run-tests step (success) 19:11:01 INFO - Running post-run listener: _resource_record_post_run 19:11:01 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 32881664; Write bytes: 552935424; Read time: 317; Write time: 1787 19:11:01 INFO - TinderboxPrint: CPU usage
53.7% 19:11:01 INFO - TinderboxPrint: I/O read bytes / time
32,881,664 / 317 19:11:01 INFO - TinderboxPrint: I/O write bytes / time
552,935,424 / 1,787 19:11:01 INFO - TinderboxPrint: CPU idle
2,229.1 (46.3%) 19:11:01 INFO - TinderboxPrint: CPU system
964.9 (20.0%) 19:11:01 INFO - TinderboxPrint: CPU user
1,623.6 (33.7%) 19:11:01 INFO - TinderboxPrint: Swap in / out
281,214,976 / 0 19:11:01 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 246370816; Write bytes: 246083584; Read time: 18945; Write time: 413 19:11:01 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 30809088; Write bytes: 296318976; Read time: 288; Write time: 1308 19:11:01 INFO - Running post-run listener: _upload_blobber_files 19:11:01 INFO - Blob upload gear active. 19:11:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:11:01 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 19:11:01 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'] 19:11:01 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 19:11:02 INFO - (blobuploader) - INFO - Open directory for files ... 19:11:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:11:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:11:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:11:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:11:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:11:03 INFO - (blobuploader) - INFO - Done attempting. 19:11:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:11:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:11:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:11:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:11:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:11:04 INFO - (blobuploader) - INFO - Done attempting. 19:11:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:11:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:11:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:11:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:11:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:11:05 INFO - (blobuploader) - INFO - Done attempting. 19:11:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:11:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:11:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:11:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:11:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:11:07 INFO - (blobuploader) - INFO - Done attempting. 19:11:07 INFO - (blobuploader) - INFO - Iteration through files over. 19:11:07 INFO - Return code: 0 19:11:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:11:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:11:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b6de4706567fb87bc07c9cf8c70c8adf1eab2240506134a646d518e36a568a60ef9b070cb1486b3842ce6adab7861e64582bd59cd1a52eb987aac74b4bc01de", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d568bf8942829fb885684be83bba516794b0797ef029c6aa2517b4909c7575695c1ef070aac4f01051bff53fc8c1b3bd30d7ac473f6ac0580310df1c10f728f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0699c63fce20b6971e2f119240dd3d65b5ef917e9a631126091fe67fcf538ead7c14cf4bcd4633511d728be892dada4f8b19e9c2af727a53e7f1e4522d006c65", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b6b8b6ece34505dc0565cc3eeaf7e66da6a3cbd7d25992952786f92d963f3feb7fa25ba00ff1bcb02a975d229e46810e65f07b0a4de5acb00ae4a4179c1de7d"} 19:11:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:11:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:11:07 INFO - Contents: 19:11:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b6de4706567fb87bc07c9cf8c70c8adf1eab2240506134a646d518e36a568a60ef9b070cb1486b3842ce6adab7861e64582bd59cd1a52eb987aac74b4bc01de", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d568bf8942829fb885684be83bba516794b0797ef029c6aa2517b4909c7575695c1ef070aac4f01051bff53fc8c1b3bd30d7ac473f6ac0580310df1c10f728f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0699c63fce20b6971e2f119240dd3d65b5ef917e9a631126091fe67fcf538ead7c14cf4bcd4633511d728be892dada4f8b19e9c2af727a53e7f1e4522d006c65", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b6b8b6ece34505dc0565cc3eeaf7e66da6a3cbd7d25992952786f92d963f3feb7fa25ba00ff1bcb02a975d229e46810e65f07b0a4de5acb00ae4a4179c1de7d"} 19:11:07 INFO - Running post-run listener: copy_logs_to_upload_dir 19:11:07 INFO - Copying logs to upload dir... 19:11:07 INFO - mkdir: /builds/slave/test/build/upload/logs 19:11:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.370616 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-02-06 19:11:07.972469) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 19:11:07.976242) ========= 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.hBrwAqDkyo/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.BiHhMEZe20/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b6de4706567fb87bc07c9cf8c70c8adf1eab2240506134a646d518e36a568a60ef9b070cb1486b3842ce6adab7861e64582bd59cd1a52eb987aac74b4bc01de", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d568bf8942829fb885684be83bba516794b0797ef029c6aa2517b4909c7575695c1ef070aac4f01051bff53fc8c1b3bd30d7ac473f6ac0580310df1c10f728f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0699c63fce20b6971e2f119240dd3d65b5ef917e9a631126091fe67fcf538ead7c14cf4bcd4633511d728be892dada4f8b19e9c2af727a53e7f1e4522d006c65", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b6b8b6ece34505dc0565cc3eeaf7e66da6a3cbd7d25992952786f92d963f3feb7fa25ba00ff1bcb02a975d229e46810e65f07b0a4de5acb00ae4a4179c1de7d"} build_url:https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011843 build_url: 'https://queue.taskcluster.net/v1/task/VhySYpqaRQKQK0_BEQ2BjQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b6de4706567fb87bc07c9cf8c70c8adf1eab2240506134a646d518e36a568a60ef9b070cb1486b3842ce6adab7861e64582bd59cd1a52eb987aac74b4bc01de", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d568bf8942829fb885684be83bba516794b0797ef029c6aa2517b4909c7575695c1ef070aac4f01051bff53fc8c1b3bd30d7ac473f6ac0580310df1c10f728f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0699c63fce20b6971e2f119240dd3d65b5ef917e9a631126091fe67fcf538ead7c14cf4bcd4633511d728be892dada4f8b19e9c2af727a53e7f1e4522d006c65", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b6b8b6ece34505dc0565cc3eeaf7e66da6a3cbd7d25992952786f92d963f3feb7fa25ba00ff1bcb02a975d229e46810e65f07b0a4de5acb00ae4a4179c1de7d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 19:11:08.012146) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 19:11:08.012462) ========= 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.hBrwAqDkyo/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.BiHhMEZe20/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.005458 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 19:11:08.061747) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 19:11:08.062152) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 19:11:08.062765) ========= ========= Total master_lag: 0.18 =========