builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0022 starttime: 1491307310.76 results: success (0) buildid: 20170404030204 builduid: f61b5cde5d1e40c9ad4abaff99cc72b9 revision: b5d8b27a753725c1de41ffae2e338798f3b5cacd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-04 05:01:50.760943) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-04 05:01:50.761399) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-04 05:01:50.761681) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-04 05:01:50.788874) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 05:01:50.789270) ========= 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.nqOXIQRE9f/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.CXilRRjgOi/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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-04-04 05:01:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 11.0M=0.001s 2017-04-04 05:01:51 (11.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.136496 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 05:01:50.947306) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 05:01:50.947641) ========= 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.nqOXIQRE9f/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.CXilRRjgOi/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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.028158 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 05:01:50.997394) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 05:01:50.997741) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b5d8b27a753725c1de41ffae2e338798f3b5cacd --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 b5d8b27a753725c1de41ffae2e338798f3b5cacd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nqOXIQRE9f/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.CXilRRjgOi/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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-04-04 05:01:51,364 truncating revision to first 12 chars 2017-04-04 05:01:51,364 Setting DEBUG logging. 2017-04-04 05:01:51,364 attempt 1/10 2017-04-04 05:01:51,364 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b5d8b27a7537?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-04 05:01:52,004 unpacking tar archive at: mozilla-central-b5d8b27a7537/testing/mozharness/ program finished with exit code 0 elapsedTime=0.924417 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 05:01:51.945512) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-04 05:01:51.945830) ========= script_repo_revision: b5d8b27a753725c1de41ffae2e338798f3b5cacd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-04 05:01:51.946204) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-04 05:01:51.946484) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-04 05:01:51.966314) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-04 05:01:51.966622) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nqOXIQRE9f/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.CXilRRjgOi/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:01:52 INFO - MultiFileLogger online at 20170404 05:01:52 in /builds/slave/test 05:01:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:01:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:01:52 INFO - {'append_to_log': False, 05:01:52 INFO - 'base_work_dir': '/builds/slave/test', 05:01:52 INFO - 'blob_upload_branch': 'Firefox', 05:01:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:01:52 INFO - 'branch': 'Firefox', 05:01:52 INFO - 'buildbot_json_path': 'buildprops.json', 05:01:52 INFO - 'config_files': ('talos/mac_config.py',), 05:01:52 INFO - 'default_actions': ('clobber', 05:01:52 INFO - 'read-buildbot-config', 05:01:52 INFO - 'download-and-extract', 05:01:52 INFO - 'populate-webroot', 05:01:52 INFO - 'create-virtualenv', 05:01:52 INFO - 'install', 05:01:52 INFO - 'run-tests'), 05:01:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:01:52 INFO - 'download_minidump_stackwalk': True, 05:01:52 INFO - 'download_symbols': 'ondemand', 05:01:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:01:52 INFO - 'tooltool.py': '/tools/tooltool.py', 05:01:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:01:52 INFO - '/tools/misc-python/virtualenv.py')}, 05:01:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:01:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:01:52 INFO - 'gecko_profile': False, 05:01:52 INFO - 'gecko_profile_interval': 0, 05:01:52 INFO - 'installer_path': 'installer.exe', 05:01:52 INFO - 'log_level': 'info', 05:01:52 INFO - 'log_name': 'talos', 05:01:52 INFO - 'log_to_console': True, 05:01:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:01:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:01:52 INFO - 'opt_config_files': (), 05:01:52 INFO - 'pip_index': False, 05:01:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:01:52 INFO - 'cmd': ('bash', 05:01:52 INFO - '-c', 05:01:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:01:52 INFO - 'enabled': True, 05:01:52 INFO - 'halt_on_failure': False, 05:01:52 INFO - 'name': 'check_screen_resolution'},), 05:01:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:01:52 INFO - 'cmd': ('bash', 05:01:52 INFO - '-c', 05:01:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:01:52 INFO - 'enabled': True, 05:01:52 INFO - 'halt_on_failure': False, 05:01:52 INFO - 'name': 'check_screen_resolution'},), 05:01:52 INFO - 'run_cmd_checks_enabled': True, 05:01:52 INFO - 'suite': 'dromaeojs-e10s', 05:01:52 INFO - 'system_bits': '32', 05:01:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:01:52 INFO - 'title': 't-yosemite-r7-0022', 05:01:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:01:52 INFO - 'use_talos_json': True, 05:01:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:01:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:01:52 INFO - 'work_dir': 'build'} 05:01:52 INFO - [mozharness: 2017-04-04 12:01:52.440092Z] Running clobber step. 05:01:52 INFO - Running pre-action listener: _resource_record_pre_action 05:01:52 INFO - Running main action method: clobber 05:01:52 INFO - rmtree: /builds/slave/test/build 05:01:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:01:55 INFO - Running post-action listener: _resource_record_post_action 05:01:55 INFO - [mozharness: 2017-04-04 12:01:55.041335Z] Finished clobber step (success) 05:01:55 INFO - [mozharness: 2017-04-04 12:01:55.041457Z] Running read-buildbot-config step. 05:01:55 INFO - Running pre-action listener: _resource_record_pre_action 05:01:55 INFO - Running main action method: read_buildbot_config 05:01:55 INFO - Using buildbot properties: 05:01:55 INFO - { 05:01:55 INFO - "project": "", 05:01:55 INFO - "product": "firefox", 05:01:55 INFO - "script_repo_revision": "production", 05:01:55 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:01:55 INFO - "repository": "", 05:01:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:01:55 INFO - "buildid": "20170404030204", 05:01:55 INFO - "stage_platform": "macosx64", 05:01:55 INFO - "basedir": "/builds/slave/test", 05:01:55 INFO - "nightly_build": "True", 05:01:55 INFO - "buildnumber": 187, 05:01:55 INFO - "slavename": "t-yosemite-r7-0022", 05:01:55 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:01:55 INFO - "platform": "yosemite_r7", 05:01:55 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:01:55 INFO - "branch": "mozilla-central", 05:01:55 INFO - "repo_path": "mozilla-central", 05:01:55 INFO - "revision": "b5d8b27a753725c1de41ffae2e338798f3b5cacd", 05:01:55 INFO - "pgo_build": "False", 05:01:55 INFO - "builduid": "f61b5cde5d1e40c9ad4abaff99cc72b9", 05:01:55 INFO - "slavebuilddir": "test" 05:01:55 INFO - } 05:01:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 05:01:55 INFO - Running post-action listener: _resource_record_post_action 05:01:55 INFO - [mozharness: 2017-04-04 12:01:55.044892Z] Finished read-buildbot-config step (success) 05:01:55 INFO - [mozharness: 2017-04-04 12:01:55.044986Z] Running download-and-extract step. 05:01:55 INFO - Running pre-action listener: _resource_record_pre_action 05:01:55 INFO - Running main action method: download_and_extract 05:01:55 INFO - mkdir: /builds/slave/test/build/tests 05:01: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')]} 05:01:55 INFO - trying https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:01:55 INFO - Downloading https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:01:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 05:01:55 INFO - Downloaded 1325 bytes. 05:01:55 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:01:55 INFO - Using the following test package requirements: 05:01:55 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:01:55 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:01:55 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:01:55 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:01:55 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:01:55 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:01:55 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:01:55 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:01:55 INFO - u'jsshell-mac.zip'], 05:01:55 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:01:55 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:01:55 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:01:55 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:01:55 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:01:55 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:01:55 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:01:55 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:01:55 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:01:55 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:01:55 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:01:55 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:01:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:01:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:01:55 INFO - Fetch https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:01:57 INFO - Expected file size: 52519065 05:01:57 INFO - Obtained file size: 52519065 05:02:13 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:02:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:02:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:02:13 INFO - Fetch https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:02:16 INFO - Expected file size: 52519065 05:02:16 INFO - Obtained file size: 52519065 05:02:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:02:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:02:31 INFO - Fetch https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:02:32 INFO - Expected file size: 14404152 05:02:32 INFO - Obtained file size: 14404152 05:02: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')]} 05:02:32 INFO - trying https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:02:32 INFO - Downloading https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:02:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 05:02:34 INFO - Downloaded 75646037 bytes. 05:02:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:02:34 INFO - mkdir: /builds/slave/test/properties 05:02:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:02:34 INFO - Writing to file /builds/slave/test/properties/build_url 05:02:34 INFO - Contents: 05:02:34 INFO - build_url:https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:02:35 INFO - Running post-action listener: _resource_record_post_action 05:02:35 INFO - Running post-action listener: set_extra_try_arguments 05:02:35 INFO - [mozharness: 2017-04-04 12:02:35.368159Z] Finished download-and-extract step (success) 05:02:35 INFO - [mozharness: 2017-04-04 12:02:35.368309Z] Running populate-webroot step. 05:02:35 INFO - Running pre-action listener: _resource_record_pre_action 05:02:35 INFO - Running main action method: populate_webroot 05:02:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:02:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:02:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:02:35 INFO - '--tppagecycles', 05:02:35 INFO - '7'], 05:02:35 INFO - 'tests': ['tsvgm']}}, 05:02:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:02:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:02:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:02:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:02:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:02:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:02:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:02:35 INFO - 'talos_options': ['--disable-e10s'], 05:02:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:02:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:02:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:02:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:02:35 INFO - 'talos_options': ['--disable-e10s'], 05:02:35 INFO - 'tests': ['damp', 'tps']}, 05:02:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:02:35 INFO - 'tests': ['damp', 'tps']}, 05:02:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:02:35 INFO - 'tests': ['dromaeo_dom']}, 05:02:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:02:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:02:35 INFO - 'tests': ['basic_compositor_video']}, 05:02:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:02:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:02:35 INFO - 'tests': ['a11yr', 05:02:35 INFO - 'ts_paint', 05:02:35 INFO - 'tpaint', 05:02:35 INFO - 'sessionrestore', 05:02:35 INFO - 'sessionrestore_no_auto_restore', 05:02:35 INFO - 'tabpaint']}, 05:02:35 INFO - 'other-e10s': {'tests': ['a11yr', 05:02:35 INFO - 'ts_paint', 05:02:35 INFO - 'tpaint', 05:02:35 INFO - 'sessionrestore', 05:02:35 INFO - 'sessionrestore_no_auto_restore', 05:02:35 INFO - 'tabpaint']}, 05:02:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:02:35 INFO - 'ts_paint', 05:02:35 INFO - 'tpaint', 05:02:35 INFO - 'sessionrestore', 05:02:35 INFO - 'sessionrestore_no_auto_restore', 05:02:35 INFO - 'tabpaint']}, 05:02:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:02:35 INFO - 'ts_paint', 05:02:35 INFO - 'tpaint', 05:02:35 INFO - 'sessionrestore', 05:02:35 INFO - 'sessionrestore_no_auto_restore', 05:02:35 INFO - 'tabpaint']}, 05:02:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:02:35 INFO - 'tests': ['a11yr', 05:02:35 INFO - 'ts_paint', 05:02:35 INFO - 'tpaint', 05:02:35 INFO - 'sessionrestore', 05:02:35 INFO - 'sessionrestore_no_auto_restore', 05:02:35 INFO - 'tabpaint']}, 05:02:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:02:35 INFO - 'tests': ['a11yr', 05:02:35 INFO - 'ts_paint', 05:02:35 INFO - 'tpaint', 05:02:35 INFO - 'sessionrestore', 05:02:35 INFO - 'sessionrestore_no_auto_restore', 05:02:35 INFO - 'tabpaint']}, 05:02:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:02:35 INFO - 'tests': ['tsvgx', 05:02:35 INFO - 'tsvgr_opacity', 05:02:35 INFO - 'tart', 05:02:35 INFO - 'tscrollx', 05:02:35 INFO - 'cart', 05:02:35 INFO - 'tsvg_static']}, 05:02:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:02:35 INFO - 'tsvgr_opacity', 05:02:35 INFO - 'tart', 05:02:35 INFO - 'tscrollx', 05:02:35 INFO - 'cart', 05:02:35 INFO - 'tsvg_static']}, 05:02:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:02:35 INFO - 'talos_options': ['--disable-e10s'], 05:02:35 INFO - 'tests': ['tp5o']}, 05:02:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:02:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:02:35 INFO - 'talos_options': ['--disable-e10s', 05:02:35 INFO - '--xperf_path', 05:02:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:02:35 INFO - 'tests': ['tp5n']}, 05:02:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:02:35 INFO - 'talos_options': ['--xperf_path', 05:02:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:02:35 INFO - 'tests': ['tp5n']}}, 05:02:35 INFO - 'talos.zip': {'path': '', 05:02:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:02:35 INFO - Running post-action listener: _resource_record_post_action 05:02:35 INFO - [mozharness: 2017-04-04 12:02:35.386553Z] Finished populate-webroot step (success) 05:02:35 INFO - [mozharness: 2017-04-04 12:02:35.386644Z] Running create-virtualenv step. 05:02:35 INFO - Running pre-action listener: _resource_record_pre_action 05:02:35 INFO - Running main action method: create_virtualenv 05:02:35 INFO - Creating virtualenv /builds/slave/test/build/venv 05:02:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:02:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:02:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:02:35 INFO - Using real prefix '/tools/python27' 05:02:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:02:36 INFO - Installing distribute.............................................................................................................................................................................................done. 05:02:39 INFO - Installing pip.................done. 05:02:39 INFO - Return code: 0 05:02:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:02:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:02:39 INFO - Reading from file tmpfile_stdout 05:02:39 INFO - Output received: 05:02:39 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) 05:02:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:02: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')]} 05:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02: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')]} 05:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1096beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3125928b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949cd30>, '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 0x1093c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109491be8>, '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.nqOXIQRE9f/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.CXilRRjgOi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02: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', 'pip>=1.5'] in /builds/slave/test/build 05:02: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 pip>=1.5 05:02:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nqOXIQRE9f/Render', 05:02:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:39 INFO - 'HOME': '/Users/cltbld', 05:02:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:39 INFO - 'LOGNAME': 'cltbld', 05:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:39 INFO - 'MOZ_NO_REMOTE': '1', 05:02:39 INFO - 'NO_EM_RESTART': '1', 05:02:39 INFO - 'PAGER': '/bin/cat', 05:02:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:39 INFO - 'PWD': '/builds/slave/test', 05:02:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:39 INFO - 'SHELL': '/bin/bash', 05:02:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXilRRjgOi/Listeners', 05:02:39 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:02:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:39 INFO - 'USER': 'cltbld', 05:02:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:39 INFO - 'XPC_FLAGS': '0x0', 05:02:39 INFO - 'XPC_SERVICE_NAME': '0', 05:02:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:02:39 INFO - Cleaning up... 05:02:39 INFO - Return code: 0 05:02:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:02: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')]} 05:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02: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')]} 05:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02: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', '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 0x1096beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3125928b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949cd30>, '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 0x1093c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109491be8>, '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.nqOXIQRE9f/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.CXilRRjgOi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:02: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 psutil>=3.1.1 05:02:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nqOXIQRE9f/Render', 05:02:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:39 INFO - 'HOME': '/Users/cltbld', 05:02:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:39 INFO - 'LOGNAME': 'cltbld', 05:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:39 INFO - 'MOZ_NO_REMOTE': '1', 05:02:39 INFO - 'NO_EM_RESTART': '1', 05:02:39 INFO - 'PAGER': '/bin/cat', 05:02:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:39 INFO - 'PWD': '/builds/slave/test', 05:02:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:39 INFO - 'SHELL': '/bin/bash', 05:02:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXilRRjgOi/Listeners', 05:02:39 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:02:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:39 INFO - 'USER': 'cltbld', 05:02:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:39 INFO - 'XPC_FLAGS': '0x0', 05:02:39 INFO - 'XPC_SERVICE_NAME': '0', 05:02:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:39 INFO - Downloading/unpacking psutil>=3.1.1 05:02: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 05:02: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 05:02: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 05:02: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 05:02: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 05:02: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 05:02:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:02:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:02:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:02:43 INFO - Installing collected packages: psutil 05:02:43 INFO - Running setup.py install for psutil 05:02:43 INFO - building 'psutil._psutil_osx' extension 05:02:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:02:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:02:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:02:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:02:44 INFO - building 'psutil._psutil_posix' extension 05:02:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:02:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:02:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:02:44 INFO - ^ 05:02:44 INFO - 1 warning generated. 05:02:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:02:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:02:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:02:44 INFO - Successfully installed psutil 05:02:44 INFO - Cleaning up... 05:02:44 INFO - Return code: 0 05:02:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:02: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')]} 05:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02: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')]} 05:02:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:44 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 0x1096beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3125928b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949cd30>, '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 0x1093c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109491be8>, '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.nqOXIQRE9f/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.CXilRRjgOi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:02:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:02:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nqOXIQRE9f/Render', 05:02:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:44 INFO - 'HOME': '/Users/cltbld', 05:02:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:44 INFO - 'LOGNAME': 'cltbld', 05:02:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:44 INFO - 'MOZ_NO_REMOTE': '1', 05:02:44 INFO - 'NO_EM_RESTART': '1', 05:02:44 INFO - 'PAGER': '/bin/cat', 05:02:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:44 INFO - 'PWD': '/builds/slave/test', 05:02:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:44 INFO - 'SHELL': '/bin/bash', 05:02:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXilRRjgOi/Listeners', 05:02:44 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:02:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:44 INFO - 'USER': 'cltbld', 05:02:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:44 INFO - 'XPC_FLAGS': '0x0', 05:02:44 INFO - 'XPC_SERVICE_NAME': '0', 05:02:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:02:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:02:47 INFO - Installing collected packages: mozsystemmonitor 05:02:47 INFO - Running setup.py install for mozsystemmonitor 05:02:47 INFO - Successfully installed mozsystemmonitor 05:02:47 INFO - Cleaning up... 05:02:47 INFO - Return code: 0 05:02:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1096beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3125928b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949cd30>, '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 0x1093c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109491be8>, '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.nqOXIQRE9f/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.CXilRRjgOi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:02:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:02:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nqOXIQRE9f/Render', 05:02:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:48 INFO - 'HOME': '/Users/cltbld', 05:02:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:48 INFO - 'LOGNAME': 'cltbld', 05:02:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:48 INFO - 'MOZ_NO_REMOTE': '1', 05:02:48 INFO - 'NO_EM_RESTART': '1', 05:02:48 INFO - 'PAGER': '/bin/cat', 05:02:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:48 INFO - 'PWD': '/builds/slave/test', 05:02:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:48 INFO - 'SHELL': '/bin/bash', 05:02:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXilRRjgOi/Listeners', 05:02:48 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:02:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:48 INFO - 'USER': 'cltbld', 05:02:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:48 INFO - 'XPC_FLAGS': '0x0', 05:02:48 INFO - 'XPC_SERVICE_NAME': '0', 05:02:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:48 INFO - Downloading/unpacking jsonschema==2.5.1 05:02:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:02:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:02: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 05:02: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 05:02: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 05:02: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 05:02: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 05:02: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 05:02:52 INFO - Downloading functools32-3.2.3-2.tar.gz 05:02:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:02:52 INFO - warning: no files found matching '*.txt' 05:02:52 INFO - no previously-included directories found matching 'build' 05:02:52 INFO - no previously-included directories found matching 'dist' 05:02:52 INFO - no previously-included directories found matching '.git*' 05:02:52 INFO - Installing collected packages: jsonschema, functools32 05:02:52 INFO - Running setup.py install for functools32 05:02:52 INFO - warning: no files found matching '*.txt' 05:02:52 INFO - no previously-included directories found matching 'build' 05:02:52 INFO - no previously-included directories found matching 'dist' 05:02:52 INFO - no previously-included directories found matching '.git*' 05:02:52 INFO - Successfully installed jsonschema functools32 05:02:52 INFO - Cleaning up... 05:02:52 INFO - Return code: 0 05:02:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1096beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3125928b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949cd30>, '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 0x1093c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109491be8>, '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.nqOXIQRE9f/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.CXilRRjgOi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:02:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nqOXIQRE9f/Render', 05:02:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:52 INFO - 'HOME': '/Users/cltbld', 05:02:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:52 INFO - 'LOGNAME': 'cltbld', 05:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:52 INFO - 'MOZ_NO_REMOTE': '1', 05:02:52 INFO - 'NO_EM_RESTART': '1', 05:02:52 INFO - 'PAGER': '/bin/cat', 05:02:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:52 INFO - 'PWD': '/builds/slave/test', 05:02:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:52 INFO - 'SHELL': '/bin/bash', 05:02:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXilRRjgOi/Listeners', 05:02:52 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:02:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:52 INFO - 'USER': 'cltbld', 05:02:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:52 INFO - 'XPC_FLAGS': '0x0', 05:02:52 INFO - 'XPC_SERVICE_NAME': '0', 05:02:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:02:52 INFO - Cleaning up... 05:02:52 INFO - Return code: 0 05:02:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1096beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3125928b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949cd30>, '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 0x1093c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109491be8>, '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.nqOXIQRE9f/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.CXilRRjgOi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:02:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nqOXIQRE9f/Render', 05:02:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:52 INFO - 'HOME': '/Users/cltbld', 05:02:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:52 INFO - 'LOGNAME': 'cltbld', 05:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:52 INFO - 'MOZ_NO_REMOTE': '1', 05:02:52 INFO - 'NO_EM_RESTART': '1', 05:02:52 INFO - 'PAGER': '/bin/cat', 05:02:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:52 INFO - 'PWD': '/builds/slave/test', 05:02:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:52 INFO - 'SHELL': '/bin/bash', 05:02:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXilRRjgOi/Listeners', 05:02:52 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:02:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:52 INFO - 'USER': 'cltbld', 05:02:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:52 INFO - 'XPC_FLAGS': '0x0', 05:02:52 INFO - 'XPC_SERVICE_NAME': '0', 05:02:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:53 INFO - Downloading/unpacking blobuploader==1.2.4 05:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:55 INFO - Downloading blobuploader-1.2.4.tar.gz 05:02:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:02:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:02:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:56 INFO - Downloading docopt-0.6.1.tar.gz 05:02:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:02:57 INFO - Installing collected packages: blobuploader, requests, docopt 05:02:57 INFO - Running setup.py install for blobuploader 05:02:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:02:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:02:57 INFO - Running setup.py install for requests 05:02:57 INFO - Running setup.py install for docopt 05:02:57 INFO - Successfully installed blobuploader requests docopt 05:02:57 INFO - Cleaning up... 05:02:57 INFO - Return code: 0 05:02:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:02: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')]} 05:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02: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')]} 05:02:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:57 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 0x1096beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3125928b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949cd30>, '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 0x1093c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109491be8>, '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.nqOXIQRE9f/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.CXilRRjgOi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:02:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:02:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nqOXIQRE9f/Render', 05:02:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:57 INFO - 'HOME': '/Users/cltbld', 05:02:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:57 INFO - 'LOGNAME': 'cltbld', 05:02:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:57 INFO - 'MOZ_NO_REMOTE': '1', 05:02:57 INFO - 'NO_EM_RESTART': '1', 05:02:57 INFO - 'PAGER': '/bin/cat', 05:02:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:57 INFO - 'PWD': '/builds/slave/test', 05:02:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:57 INFO - 'SHELL': '/bin/bash', 05:02:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXilRRjgOi/Listeners', 05:02:57 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:02:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:57 INFO - 'USER': 'cltbld', 05:02:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:57 INFO - 'XPC_FLAGS': '0x0', 05:02:57 INFO - 'XPC_SERVICE_NAME': '0', 05:02:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:02:58 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-iayQ1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:02:58 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-lOF7tq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:02:58 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-3TxY91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:02:58 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-HnQWLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:02:58 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-rorsOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:02:58 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-Q4udgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:02:58 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-6tZAeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:02:58 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-LUuTRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:02:58 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-w5ZSG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:02:59 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-5MQNHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:02:59 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-N5ysBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:02:59 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-q6cyE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:02:59 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-nLT5da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:02:59 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-Wdjdbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:59 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-MVvXVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:02:59 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-z4bTnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:02:59 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-0Qq8IZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:02:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:02:59 INFO - Running setup.py install for manifestparser 05:03:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:03:00 INFO - Running setup.py install for mozcrash 05:03:00 INFO - Running setup.py install for mozdebug 05:03:00 INFO - Running setup.py install for mozdevice 05:03:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:03:00 INFO - Running setup.py install for mozfile 05:03:00 INFO - Running setup.py install for mozhttpd 05:03:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:03:00 INFO - Running setup.py install for mozinfo 05:03:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:03:00 INFO - Running setup.py install for mozInstall 05:03:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:03:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:03:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:03:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:03:01 INFO - Running setup.py install for mozleak 05:03:01 INFO - Running setup.py install for mozlog 05:03:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:03:01 INFO - Running setup.py install for moznetwork 05:03:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:03:01 INFO - Running setup.py install for mozprocess 05:03:01 INFO - Running setup.py install for mozprofile 05:03:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:03:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:03:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:03:01 INFO - Running setup.py install for mozrunner 05:03:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:03:02 INFO - Running setup.py install for mozscreenshot 05:03:02 INFO - Running setup.py install for moztest 05:03:02 INFO - Running setup.py install for mozversion 05:03:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:03:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:03:02 INFO - Cleaning up... 05:03:02 INFO - Return code: 0 05:03:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:03:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:02 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 0x1096beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3125928b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949cd30>, '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 0x1093c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109491be8>, '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.nqOXIQRE9f/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.CXilRRjgOi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:03:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:03:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nqOXIQRE9f/Render', 05:03:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:02 INFO - 'HOME': '/Users/cltbld', 05:03:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:02 INFO - 'LOGNAME': 'cltbld', 05:03:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:02 INFO - 'MOZ_NO_REMOTE': '1', 05:03:02 INFO - 'NO_EM_RESTART': '1', 05:03:02 INFO - 'PAGER': '/bin/cat', 05:03:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:02 INFO - 'PWD': '/builds/slave/test', 05:03:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:02 INFO - 'SHELL': '/bin/bash', 05:03:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXilRRjgOi/Listeners', 05:03:02 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:03:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:02 INFO - 'USER': 'cltbld', 05:03:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:02 INFO - 'XPC_FLAGS': '0x0', 05:03:02 INFO - 'XPC_SERVICE_NAME': '0', 05:03:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:03:02 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-uLuFO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:03:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:03:02 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-QDIiZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:03:02 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)) 05:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:03:02 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-943Klb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:03:03 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-tjesX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:03:03 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)) 05:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:03:03 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-97xJnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:03:03 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-8pLJLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:03:03 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-68bXgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:03:03 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-XZVgXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:03:03 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-YL0DMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:03:03 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-hCPcGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:03:03 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)) 05:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:03:03 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-opOseO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:03:03 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-nXvtFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:03:04 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-p4ZR_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:03:04 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-fLYnDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:03:04 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)) 05:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:03:04 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-9iu3ZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:03:04 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-R6Cboy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:03:04 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)) 05:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:03:04 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-sEym4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:03:04 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)) 05:03:04 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)) 05:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:03:04 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)) 05:03:04 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)) 05:03:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:07 INFO - Downloading blessings-1.6.tar.gz 05:03:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:03:07 INFO - Installing collected packages: blessings 05:03:07 INFO - Running setup.py install for blessings 05:03:07 INFO - Successfully installed blessings 05:03:07 INFO - Cleaning up... 05:03:07 INFO - Return code: 0 05:03:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:03:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:03:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:03:07 INFO - Reading from file tmpfile_stdout 05:03:07 INFO - Current package versions: 05:03:07 INFO - blessings == 1.6 05:03:07 INFO - blobuploader == 1.2.4 05:03:07 INFO - docopt == 0.6.1 05:03:07 INFO - functools32 == 3.2.3-2 05:03:07 INFO - jsonschema == 2.5.1 05:03:07 INFO - manifestparser == 1.1 05:03:07 INFO - mozInstall == 1.12 05:03:07 INFO - mozcrash == 1.0 05:03:07 INFO - mozdebug == 0.1 05:03:07 INFO - mozdevice == 0.49 05:03:07 INFO - mozfile == 1.2 05:03:07 INFO - mozhttpd == 0.7 05:03:07 INFO - mozinfo == 0.9 05:03:07 INFO - mozleak == 0.1 05:03:07 INFO - mozlog == 3.4 05:03:07 INFO - moznetwork == 0.27 05:03:07 INFO - mozprocess == 0.25 05:03:07 INFO - mozprofile == 0.28 05:03:07 INFO - mozrunner == 6.13 05:03:07 INFO - mozscreenshot == 0.1 05:03:07 INFO - mozsystemmonitor == 0.3 05:03:07 INFO - moztest == 0.8 05:03:07 INFO - mozversion == 1.4 05:03:07 INFO - psutil == 3.1.1 05:03:07 INFO - requests == 1.2.3 05:03:07 INFO - wsgiref == 0.1.2 05:03:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:03:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:07 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 0x1096beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3125928b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949cd30>, '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 0x1093c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109491be8>, '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.nqOXIQRE9f/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.CXilRRjgOi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:03:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:03:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:03:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nqOXIQRE9f/Render', 05:03:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:07 INFO - 'HOME': '/Users/cltbld', 05:03:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:07 INFO - 'LOGNAME': 'cltbld', 05:03:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:07 INFO - 'MOZ_NO_REMOTE': '1', 05:03:07 INFO - 'NO_EM_RESTART': '1', 05:03:07 INFO - 'PAGER': '/bin/cat', 05:03:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:07 INFO - 'PWD': '/builds/slave/test', 05:03:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:07 INFO - 'SHELL': '/bin/bash', 05:03:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXilRRjgOi/Listeners', 05:03:07 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:03:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:07 INFO - 'USER': 'cltbld', 05:03:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:07 INFO - 'XPC_FLAGS': '0x0', 05:03:07 INFO - 'XPC_SERVICE_NAME': '0', 05:03:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:03:08 INFO - Cleaning up... 05:03:08 INFO - Return code: 0 05:03:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:08 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 0x1096beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3125928b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949cd30>, '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 0x1093c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109491be8>, '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.nqOXIQRE9f/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.CXilRRjgOi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:03:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:03:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:03:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nqOXIQRE9f/Render', 05:03:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:08 INFO - 'HOME': '/Users/cltbld', 05:03:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:08 INFO - 'LOGNAME': 'cltbld', 05:03:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:08 INFO - 'MOZ_NO_REMOTE': '1', 05:03:08 INFO - 'NO_EM_RESTART': '1', 05:03:08 INFO - 'PAGER': '/bin/cat', 05:03:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:08 INFO - 'PWD': '/builds/slave/test', 05:03:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:08 INFO - 'SHELL': '/bin/bash', 05:03:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXilRRjgOi/Listeners', 05:03:08 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:03:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:08 INFO - 'USER': 'cltbld', 05:03:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:08 INFO - 'XPC_FLAGS': '0x0', 05:03:08 INFO - 'XPC_SERVICE_NAME': '0', 05:03:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:03:08 INFO - Cleaning up... 05:03:08 INFO - Return code: 0 05:03:08 INFO - Running post-action listener: _resource_record_post_action 05:03:08 INFO - Running post-action listener: _start_resource_monitoring 05:03:08 INFO - Starting resource monitoring. 05:03:08 INFO - [mozharness: 2017-04-04 12:03:08.427343Z] Finished create-virtualenv step (success) 05:03:08 INFO - [mozharness: 2017-04-04 12:03:08.428281Z] Running install step. 05:03:08 INFO - Running pre-action listener: _resource_record_pre_action 05:03:08 INFO - Running main action method: install 05:03:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:03:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:03:08 INFO - Reading from file tmpfile_stdout 05:03:08 INFO - Detecting whether we're running mozinstall >=1.0... 05:03:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:03:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:03:08 INFO - Reading from file tmpfile_stdout 05:03:08 INFO - Output received: 05:03:08 INFO - Usage: mozinstall [options] installer 05:03:08 INFO - Options: 05:03:08 INFO - -h, --help show this help message and exit 05:03:08 INFO - -d DEST, --destination=DEST 05:03:08 INFO - Directory to install application into. [default: 05:03:08 INFO - "/builds/slave/test"] 05:03:08 INFO - --app=APP Application being installed. [default: firefox] 05:03:08 INFO - mkdir: /builds/slave/test/build/application 05:03:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:03:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:03:28 INFO - Reading from file tmpfile_stdout 05:03:28 INFO - Output received: 05:03:28 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:03:28 INFO - Running post-action listener: _resource_record_post_action 05:03:28 INFO - [mozharness: 2017-04-04 12:03:28.966734Z] Finished install step (success) 05:03:28 INFO - [mozharness: 2017-04-04 12:03:28.966879Z] Running run-tests step. 05:03:28 INFO - Running pre-action listener: _resource_record_pre_action 05:03:28 INFO - Running main action method: run_tests 05:03:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:03:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:03:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:03:28 INFO - 2017-04-04 05:03:28.980 screenresolution[1622:7333] starting screenresolution argv=screenresolution get 05:03:28 INFO - 2017-04-04 05:03:28.983 screenresolution[1622:7333] Display 0: 1600x1200x32@60 05:03:28 INFO - 2017-04-04 05:03:28.989 screenresolution[1623:7335] starting screenresolution argv=screenresolution list 05:03:28 INFO - Available Modes on Display 0 05:03:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:03:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:03:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:03:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:03:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:03:29 INFO - Intel Iris: 05:03:29 INFO - Chipset Model: Intel Iris 05:03:29 INFO - Type: GPU 05:03:29 INFO - Bus: Built-In 05:03:29 INFO - VRAM (Dynamic, Max): 1536 MB 05:03:29 INFO - Vendor: Intel (0x8086) 05:03:29 INFO - Device ID: 0x0a2e 05:03:29 INFO - Revision ID: 0x0009 05:03:29 INFO - Displays: 05:03:29 INFO - Display: 05:03:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:03:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:03:29 INFO - Display Serial Number: 1600x1200 60 05:03:29 INFO - Main Display: Yes 05:03:29 INFO - Mirror: Off 05:03:29 INFO - Online: Yes 05:03:29 INFO - Rotation: Supported 05:03:29 INFO - Return code: 0 05:03:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:03:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:03:29 INFO - Python 2.7.3 05:03:29 INFO - Return code: 0 05:03:29 INFO - grabbing minidump binary from tooltool 05:03:29 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')]} 05:03:29 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 0x10949cd30>, '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 0x1093c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109491be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:03:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:03:29 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:03:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:03:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:03:29 INFO - Return code: 0 05:03:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:03:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:03:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:03:29 INFO - ENV: RUST_BACKTRACE is now 1 05:03:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:03:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:03:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:03:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0022 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:03:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nqOXIQRE9f/Render', 05:03:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:29 INFO - 'HOME': '/Users/cltbld', 05:03:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:29 INFO - 'LOGNAME': 'cltbld', 05:03:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:03:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:03:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:29 INFO - 'MOZ_NO_REMOTE': '1', 05:03:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:03:29 INFO - 'NO_EM_RESTART': '1', 05:03:29 INFO - 'PAGER': '/bin/cat', 05:03:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:29 INFO - 'PWD': '/builds/slave/test', 05:03:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:03:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:29 INFO - 'RUST_BACKTRACE': '1', 05:03:29 INFO - 'SHELL': '/bin/bash', 05:03:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXilRRjgOi/Listeners', 05:03:29 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:03:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:29 INFO - 'USER': 'cltbld', 05:03:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:29 INFO - 'XPC_FLAGS': '0x0', 05:03:29 INFO - 'XPC_SERVICE_NAME': '0', 05:03:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:03:29 INFO - mozversion application_buildid: 20170404030204 05:03:29 INFO - mozversion application_changeset: b5d8b27a753725c1de41ffae2e338798f3b5cacd 05:03:29 INFO - mozversion application_display_name: Nightly 05:03:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:03:29 INFO - mozversion application_name: Firefox 05:03:29 INFO - mozversion application_remotingname: firefox 05:03:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:03:29 INFO - mozversion application_vendor: Mozilla 05:03:29 INFO - mozversion application_version: 55.0a1 05:03:29 INFO - mozversion platform_buildid: 20170404030204 05:03:29 INFO - mozversion platform_changeset: b5d8b27a753725c1de41ffae2e338798f3b5cacd 05:03:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:03:29 INFO - mozversion platform_version: 55.0a1 05:03:29 INFO - using testdate: 1491307409 05:03:29 INFO - actual date: 1491307409 05:03:29 INFO - starting webserver on 'localhost:49242' 05:03:29 INFO - SUITE-START | Running 2 tests 05:03:29 INFO - TEST-START | dromaeo_css 05:03:29 INFO - operating with platform_type : mac_ 05:03:29 INFO - Initialising browser for dromaeo_css test... 05:03:29 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmppDJVUW/profile http://localhost:49242/getInfo.html) 05:03:32 INFO - PID 1633 | 2017-04-04 05:03:32.887 plugin-container[1636:7514] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 05:03:32 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:03:32 INFO - PID 1633 | 2017-04-04 05:03:32.890 plugin-container[1636:7514] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950f, name = 'com.apple.CFPasteboardClient' 05:03:32 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:03:32 INFO - PID 1633 | 2017-04-04 05:03:32.890 plugin-container[1636:7514] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:03:32 INFO - PID 1633 | __metrics Screen width/height:1600/1200 05:03:32 INFO - PID 1633 | colorDepth:24 05:03:32 INFO - PID 1633 | Browser inner width/height: 1024/689 05:03:32 INFO - PID 1633 | __metrics 05:03:33 ERROR - PID 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 05:03:33 INFO - TEST-INFO | 1633: exit 0 05:03:33 INFO - Browser initialized. 05:03:33 INFO - Running cycle 1/1 for dromaeo_css test... 05:03:33 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmppDJVUW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:03:36 INFO - PID 1637 | 2017-04-04 05:03:36.231 plugin-container[1638:7647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 05:03:36 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:03:36 INFO - PID 1637 | 2017-04-04 05:03:36.231 plugin-container[1638:7647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 05:03:36 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:03:36 INFO - PID 1637 | 2017-04-04 05:03:36.231 plugin-container[1638:7647] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:03:45 INFO - PID 1637 | RSS: Main: 228147200 05:03:45 INFO - PID 1637 | 05:06:48 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 05:06:50 INFO - PID 1637 | RSS: Main: 252817408 05:06:50 INFO - PID 1637 | 05:08:34 INFO - PID 1637 | ************************* 05:08:34 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 05:08:34 INFO - PID 1637 | 05:08:34 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:08:34 INFO - PID 1637 | Full stack: 05:08:34 INFO - PID 1637 | ************************* 05:08:34 INFO - PID 1637 | ************************* 05:08:34 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 05:08:34 INFO - PID 1637 | 05:08:34 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:08:34 INFO - PID 1637 | Full stack: 05:08:34 INFO - PID 1637 | ************************* 05:08:34 INFO - PID 1637 | ************************* 05:08:34 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 05:08:34 INFO - PID 1637 | 05:08:34 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:08:34 INFO - PID 1637 | Full stack: 05:08:34 INFO - PID 1637 | ************************* 05:08:34 INFO - PID 1637 | ************************* 05:08:34 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 05:08:34 INFO - PID 1637 | 05:08:34 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:08:34 INFO - PID 1637 | Full stack: 05:08:34 INFO - PID 1637 | ************************* 05:09:53 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 05:09:53 INFO - PID 1637 | RSS: Main: 263245824 05:09:53 INFO - PID 1637 | 05:12:55 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 05:12:56 INFO - PID 1637 | RSS: Main: 277512192 05:12:56 INFO - PID 1637 | 05:15:58 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 05:15:59 INFO - PID 1637 | RSS: Main: 270180352 05:15:59 INFO - PID 1637 | 05:19:01 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 05:19:02 INFO - PID 1637 | RSS: Main: 279994368 05:19:02 INFO - PID 1637 | 05:22:05 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 05:22:05 INFO - PID 1637 | RSS: Main: 273694720 05:22:05 INFO - PID 1637 | 05:22:05 INFO - PID 1637 | __start_tp_report 05:22:05 INFO - PID 1637 | _x_x_mozilla_page_load 05:22:05 INFO - PID 1637 | _x_x_mozilla_page_load_details 05:22:05 INFO - PID 1637 | |i|pagename|runs| 05:22:05 INFO - PID 1637 | |0;dojo.html;2920;2985.9552106718415;3136.5138403547453;3498.4577390504483;3572.481990111434;5993.400659934005;6151.69241537923;6186.257649082111;6213.285472170702;6241.032639940805;6043.153958445821;6052.455279024887;6112.510999120071;6112.8986537897235;6114.908276375858;5313.22958171065;5357.303550538424;5377.462253774611;5379.112446446332;5435.320584926884;5264.315638966929;5361.195820626917;5408.567314614841;5433.1035379162395;5445.455454454543;5374.381946076212;5392.379876314235;5424.566034717213;5433.510984011418;5476.2333273341765;5173.793048278102;5274.367075950872;5277.70972596507;5283.471652834724;5284.5772338212855;5215.582753379758;5303.628745987782;5310.070737620939;5344.946550534483;5376.838694839161;4641.976790116028;5874.73563689635;6077.939220607782;6132.754689812402;6178.289496707846;6295.590786598902;6447.194100737408;6467.579607325509;6475.805725828232;6483.124778154983;5816.651000939957;6371.745130194787;6541.967290163567;6577.736890524373;6589.242237142694;6196.194494715748;6201.658908760016;6230.034344676537;6233.283172435137;6240.219972503437;6553.63954982471;6669.499787515859;6672.466202703772;6677.666116694146;6685.064091027163;6266.436020758154;6354.841128971812;6379.106925030499;6385.457236134887;6691.464682825363;6644.103046088813;6657;6665.266820649725;6672.466202703772;6723.495737819683;6457.418832305115;6471.6764161791725;6508.707108180049;6519.902201466982;6539.673016349164;6255.155554000152;6337.651429171347;6355.523335749745;6424.614523128627;6517.8696426072165;5228.503292187236;5275.657082289639;5276.34045744282;5280.339957505312;5283;4081.2449696806184;4725.243960966229;4730.053989202104;4748.620110391161;4790.377250957354;5445.863853403617;5896.882062358729;6136.274005700925;6246.781362652286;6321.431071203614;6527.053577231275;6556.475481961432;6583.144191255106;6597.109390232353;6602.735890564323;6315.273743519462;6315.368463153648;6318.210223722035;6328.082428047907;6351.396617321347;5640.5487560995025;5695.515881150066;5819.185314056035;5819.476247137778;5855.3851845556455;6556.770513032021;6700.5309628327495;6745.4266387355665;6751.459883209233;6777.864442711217;6572.671366431564;6599.799097437643;6628.602283862983;6641;6663.233728121328;6443.71003304843;6501.382368674968;6717.899231511434;6796.184457865088;6798.456123510206;6126.30393285589;6489.9026514603265;6496.480281577557;6512.5766825156215;6523.575967562093;6358.141650877257;6500.70746816385;6522.608643481406;6556.3443655634055;6557.704903279268;4633.443986721615;6359.682015899094;6384.808455746335;6413.390727880844;6700.363465470772;6401.423871851555;6401.583897046677;6404.519661025349;6405.551611387158;6415.422611964946;6009.90985135232;6048;6143.511877689543;6144.801763656044;6267.96866015667;6354.682265886595;6383.32975037633;6396.296407395276;6397.96801015992;6399.6800159992745;6240.157578726814;6275.246970363585;6302.65335406557;6324.051392291193;6336.619802811846 05:22:05 INFO - PID 1637 | |1;ext.html;692.3595674001549;1010.8938561451052;1039.656913218638;1190.4702407428692;1214.5870404062616;29887.850560747193;36414.453783193276;36624.08439789007;36665.816670916676;36777.97721125337;35733.106672333204;35939.82030089846;36151.638483615156;36299.092522686944;36319.636803631955;8246.299064579503;8491.363147763943;8495.957520212422;8500.74497765068;8502.319814414832;7626.923730762677;8071;8389.07720150783;8424.4945303282;8438.071812100665;8459.873101903477;8473.237408633222;8529.232369086782;8537.615807288656;8550.572471376438;8443.071262161156;8473.279771219453;8485;8493.702720404786;8522.659093636246;8318.04342500614;8380.622871970776;8386.371022173362;8390.160983901622;8412.915870841274;36028.63971360305;36138.91583252507;36297.81851090754;36355;36449.63550364489;2961.6297962754657;2984.1346009657173;2993.266649670823;3014;3036.893708720206;26006.34984125412;26145.82343794534;26425.471490570275;26630;26748;2813.08574713219;2867.7992540522173;2891.479533683936;2893.1609833148364;2893.9276518087004;264734;321915;324595;324704;325602.3719881409;37381.62618373836;44274.778626106665;44406;44835.32747008798;44920.32619510645;44704.77647611806;44723.881902952686;44814.77592612081;44831.55168448339;44909.32636010463;13141.342932853318;13171.538996135088;13199.934000329937;13203.273819939996;13322.0008499361;10708.411037393018;10730.570777168981;10911;10918.01737843582;10962.945185274022;10741.301815381976;11977.580784672495;12360.320182389874;12373.93813030929;12382.876171238173;5432.91850622241;5466.426025267369;5475.4250803665045;5483.835484935458;5490.945090549123;793.2503783924252;919.7240827751648;929.8930622978309;933.5892207428709;970.9854352184723;22851.657225141636;23078.8846055772;23157.484290563178;23169.768302317098;23358.64962025571;1027.8098551767946;1029.3103620574234;1062.3785085724783;1063.0751246415668;1069.4225118435957;11730.589429369931;11878.109141814226;11880.643580692593;11937.940310298567;12009.759804803854;474919;478831;481363;482559;485863.5706821443;48922.75538622355;49378.765530862016;49497.25754113691;49511.752441238285;49631.25553116706;1341.2086868747485;1395.3093218856732;1414.8302203735618;1425.123549017347;1433.4839457795185;22184.445388865406;22837;22872.542549149257;22993.88503057474;23065.423364416023;33860.98417047493;35676.57293708326;35724.10689732777;35918.28163436769;35948.82025589855;435669;438326;443897;446495;448167;24479.755202447748;30423.391532169677;30744.84627576848;30939.381212376076;30946.226344341572;29532.557011644414;33170.50244246387;38597.842064738106;39944.40083398693;40034.99912502211;27430.725692742817;28487.572686409305;29136.27159320949;29140.854295728386;29346.266343341587;26462.470750584496;28225.43549129047;28563.857180713963;29045.7095429043;29302.560461592668;32687.50968735423;32934.67065329412;33051.33897322088;33102.33795324128;33594;26740.8662956684;28627;29454.93971810166;29535.85232073826;29703;21508.569828603653;22162.667559986934;23004;23259;23490.882545587163 05:22:05 INFO - PID 1637 | |2;jquery.html;413.8903190654475;418.3682639214785;424.1813300330362;430.32224246811273;438.94732632084134;18990.620187596258;20648.690269645947;21354.786452135475;21403.67894481584;21408.357749267507;19317.130729117187;19479.902600486977;19614.313499027503;20015.399538013848;20148.294809681665;5865.296164460271;6078.513718902499;6115.48018418435;6120.357362476942;6124.418180272867;5939.079442686369;5975.731092100866;6035.5473339499495;6037.396260373971;6279.120923070774;5281.287026251464;5467.863303417406;6019.578629495937;6203.255609326887;6228.439440450358;5852.268466441652;6138.355472675349;6156.3843615638525;6214.067889816519;6232;5319.973400132974;5330.173823057432;5330.760115794798;5340.385855626614;5353.143497040455;22535.88732056329;22976.73627950462;23772.167974120995;24936.00255989758;25486.74513254862;1947.094601010537;1992.3624440179149;2049.8565100442975;2052.702358158074;2059.5468996820678;15315.31081101353;16033.759493607606;16088.678226435522;16357.59106022347;16804.579885502837;1756.745271935575;1879.4643526594991;1884.7926728059902;1945.3872030310406;1954.9736388396063;146061;158713;159103;159176;161042.1947890253;11866.76266474683;12001.63995080149;12099.395030248452;12205.75588488243;12248.693782655326;13025.413856376486;15611.297491612904;15637.139957302457;15656.92171539135;15743.606409839616;7198.8841729532005;7280.381167600814;8507.53208573522;8571.4000019998;8604.397692161614;6666;8134.959325203455;8191.631376588068;8225.259726624512;8276.917230827736;6422.68334991335;7365.668544915492;7423.665935032936;7450.739224127129;7500.287472690195;2080.147139672727;2081.39639504542;2082.2087606709656;2094.193735411847;2220.977790222077;874.6588830356167;879.098923603299;880.8546589812737;910.9544522773834;911.6945823149186;18489.352872649393;19766.70349944752;20254.69617955732;20341.694874576893;20740.170393184104;766.5707203965796;804.8631732605471;875.4003507597189;920.2683866326287;936.7189843047195;8517.787055323666;10025.749356266151;10400.739981500523;10405.895941040493;10520.053195212467;601163;695610;698385;705363;718698;153127;166380;169291;169920;172722;1091.4815462655333;1133.4672703829021;1166.445938179358;1167.8496680769435;1174.4714878304626;20488;20730.274440394514;20842.895785520974;20988.47528811792;21118.78881211168;64796.67601661962;65281.67359163174;65483;66158;66300;73095;89312.6603100967;89911.65132522887;90708.54645726993;91069;8478.448900821428;8515.872261915953;8572.742817715478;8678.869816952625;8713.956430217808;13323.400446979715;13405.865941340462;13430.261335626243;13743.587692369245;13813.51652692151;11567.190296679488;11710.765784684429;11791.587294444653;11862.466189021687;11996.160268781101;9136.771580710536;10438.791224175626;10602.628907988183;10859.945700271765;10961.12311015101;11706.590269340531;11804.735782237263;11858.644240672793;11936.98535624447;12066.698332541757;7455.552666840007;7516.736914207976;8197.180281971756;8553.401261911606;8713.823633809354;10976.23166378346;11421.657350279505;11453.96914277719;11609.6517104487;11693.181477296508 05:22:05 INFO - PID 1637 | |3;mootools.html;1161.8489596352472;1342.5636668082875;1376.4769387632698;1392.7326133218767;1397.3222986851379;9628.903710962888;9982.800343993125;10755.516001779919;10956.671299860996;11013.559457621706;10649.627263045795;10766.515506802212;10838.241323107386;10867.510962006709;10868.239223254375;1974.5359840437486;2055.2498338106634;2439.4389290463205;2444.278937713379;2449.9020039198413;1925.7785354684252;2016.889071101096;2329.010170677464;2397.1729753235104;2411.626197939322;1999.7000449932555;2369.4194922244073;2383.165891937825;2396.341006223294;2411.7347091819884;2304.631258998569;2339.19297842245;2342.590046741822;2353.176388264111;2355.8115350771895;2000.4998750312423;2082.427332483564;2334.5447637710654;2397.484540823714;2422.794062504689;10757.30077544957;10776.40729759863;10788.298760580537;10857.782844343068;10867.913208679149;5237.869053273661;6039.245094363205;6279.120923070774;6286.842828929268;6288.465480434169;3791.7345785795005;3861.38217885137;3920.0787814863484;3949.9012524686827;4018.2967980603275;1208.3776854919724;1559.8128224613006;1588.4122874536495;1622.7403615421476;1668.7413450915128;12137.93931030348;12349.814752778715;13741.450341986309;14758.778618320734;14916.328765205591;5323.228131920927;5460.6996615186545;5512.586556008316;5521.861953451196;5571.13647384656;12580.4338804754;12668.80996785049;12731.172473789175;12746.68133296675;12772;6253.437190652863;6269.529785266123;6304.085907543381;6308.243010838729;6328.873422531524;5502.8349149525575;5596.664200148004;5612.326520817528;5622.57830662702;5686.9715651421475;4260.957390426118;4379.824807007684;4425.712328698624;4428.13651337986;4431.446069241345;1182.8580570331615;1355.3697530648296;1396.678763884292;1396.6927275999265;1407.90848594841;605.3462260758371;651.3260919437096;721.3255606008399;747.8392145688595;755.1165136789969;6185.195924529872;6374.426301632875;6520.673966301666;6584.473242140617;6695.263521012685;749.9437542184249;851.9531425771518;876.3252295732378;876.5179151466668;882.1443200095897;3257.657945915692;3556.359855226084;3624.0939765058733;4029.4157347184496;4031.455753473302;11128.499217535069;12946.741065178643;13506.459741610226;13506.729865402636;13688.452461901612;13076.280804555841;13237.602871913858;13397;13424.664383390493;13529.729405411837;9907.405555666683;9915.752106197402;10075.042870927104;10129.696109116738;10150.54322555472;5214.608904332114;5859.677717725395;6202.472789812917;6436.9034464484;6717.555725519037;11924.821127683264;12022.699432514257;12238.632841014783;12366.381680916098;12415.813762793385;9871.358361706467;12418;12666.493340266656;12722.363881805688;12845.293508857103;8145.470544414594;9879.456629885426;9917.45454000037;9975.700728978143;10082.445465499468;9736.318457707892;9792.804143917225;9944.005599439997;9985.1512621428;10046;8352.0812710603;8495.235428811437;8524.232819046316;8667.95666021691;8733;8116.75649730509;8166.305864001626;8269.793255168668;8364.205400487188;8543.487390756325;7577;7672.61636918163;7715.961420192863;7748.92251077482;7765.1846556110795;5772.134179873053;5790.971045144747;5845.678487683048;5977.282726072899;6028.156058151774;5763.135529670415;5786.884262314814;5906.811606471492;5940.55445841555;6066.1507388966465 05:22:05 INFO - PID 1637 | |4;prototype.html;389.92786334528114;399.28328650073115;401.09552958079524;401.67198436843006;414.08074075552264;5764.250647415841;6081.817545473632;6260.655663938487;6314.242290925084;6531;4048.5951404859497;6045.697715114248;6084.604500707451;6093.969530152354;6237.781677641284;4619.422572178478;4630.560096790817;4639.489656137839;4651.976740116294;4661.696989695659;4239.215745087154;4659.720416775004;4664.626829853603;4664.97667511662;4684.3910291662205;4102.5076990761;4222.6506698101;4299.957000430002;4478.440194975628;4577.404937358156;4182.8288079337835;4212.336127229727;4598.609118224955;4611.262198048296;4632;3674.779513229215;3846.9807650961848;4059.3707975263883;4087.97956010221;4096.877093687194;5452.754626041838;5771.220885180489;5920.289565252154;6018.916595012896;6029.728662210211;5473.343198816167;5501.449855014506;5525.11598144295;5547.750351234205;5581.372108129707;6038.396160383971;6285.308616052231;6492.448141907944;6569.948808190715;6606.306337834554;3797.9240415191543;4669.1090108506105;4673.626109911199;4673.742944138071;4680.625549956029;6369.649494424659;6795;6898;6899.965500172517;6930.896036559456;4643.233866412072;4763.595094416944;4791.856244312677;4803.207470767354;4804.519548045238;4879.365682461257;4889.902201955941;4892.926606100911;4908.607311415079;4931.704097754075;5073.365829271341;5395.352557693023;5406.378266499324;5530.5852061095575;5539.335279766454;6046.939530604637;6608.116686744254;6609.471242300604;6631.966840165768;6643.634600097041;1214.7084699672016;1349.2039696579066;1466.4647403697786;1533.7622668486401;1541.3372249932636;839.8992120945526;919.2599957034518;956.0057540158297;1020.5304361719101;1029.3926583315879;4665.883352916137;5284.894302114013;5967.283925928917;6194.008958566697;6198.2252218472695;4985.651004429727;5749.712514374264;6030.33666296707;6439.871202575922;6688.130543029472;5956.195913551615;6103.633781973096;6138.907916381258;6161.7227224775;6179.196704428486;3326.4511355626532;3334.7999120052873;3512.420450625669;3580.8925732228076;3595.6044835068124;5614.9719251403485;6727.226368967534;6957.095577574984;7001.824954376181;7048.365647091787;5568.9443105569235;6046.455818976313;6713.731450741916;6856.41720453767;6974.79075627732;6026.239270976055;6471.838204044749;6547.967260163764;6990.693071286463;7019.227884932653;6206.410391012847;6730.384707670222;6762.289959554373;6777.6278302595665;6846.863062738817;5670.631408958405;6328.588641738273;6393.1688880445245;6664.5334826561675;6665.333466653296;5766.59633825645;6198.512357034369;6215.979806562795;6926.822440185281;6962.582245065312;4400.129944773523;4403.911921761611;4408.625266852225;4420.535843736361;4420.889477763081;5423.186522021729;5545.972270138624;5734.319517761761;6447.3874981878525;6775;6206.596571222856;6501.7335957874275;6514.837129071811;6531.412172904462;6537.313582074004;5330.653507521999;6008.309044459943;6153.661548614869;6537.738490460329;6542.509311801538;6199.9540151748915;6386.265579458422;6516.120323756232;6529.771457998947;6601.636909969998;5524.696141712245;6222.377762223741;6333.20834895638;6419.614823110628;6663.933360666331;5143.8456846294675;6428.742850285937;6432.614043157426;6448.645324507197;6472 05:22:05 INFO - PID 1637 | |5;yui.html;673.4208580620663;677.1772296659558;680.8161796314992;689.111046749693;693.2582137113288;14481.565553033399;17752.822471775293;18425;18574.44276671702;18581.349652762172;16246.268917898693;16376.754348684779;16428;16468;16776.986761588596;814.5967745965755;827.5241736001793;837.3970741066422;839.08539691736;852.0882655558555;815.0138332617511;823.0534884882373;830.1366578758084;834.5785378383907;840.9831803363929;837.9287760540361;859.1194026123211;876.7720392697912;893.1782759860944;895.4582477601056;859.7162936231028;873.5806812729893;874.9693760718408;880.330948479154;880.6081293824251;621.6674079367517;643.7875501084677;647.0876064748682;659.947204223661;664.9900251496232;15049.54851354461;15294.770578441332;15472;15526.990745601499;15763.842361576351;11503.884961150448;13830.515931942333;13902.096363736326;14119.576412707635;14414.351354189086;11372.715682107932;11496.482658280314;11589.304641721523;12087.093467989936;12094.971927386183;2238.6530087836413;2756.54517004694;2791.0649932272713;2797.2866918935542;2830.872610732522;97278;112480;114913;119450;121100.39449802783;9136.58885350161;10918.399488028075;10975.28660637056;11050.834237486444;11542.596009139808;17686.027268500355;20589.38231853047;20709.275175368788;20804.271850485162;20904.79095209059;6588;7759.913612094311;7776.027996500437;7934.523928564305;8073;4092.242935057024;4226.408302837605;4239.59723826236;4268.103698223345;4269.423627810206;3878.70909681775;3924.7645141291614;3932.528096628366;3961.8217180226957;3973.2848087344555;683.4361651637419;695.2526034512919;732.3006528765017;742.3504433620582;802.8318796151585;517.6350672775684;541.2909089093222;555.3141869790886;574.3854076138492;590.1943846649293;9992.350497217803;10201.183905287558;10248.846267305997;10378.014088661565;10482.947585262025;533.6184627990928;565.7143142712986;570.5920267009109;584.570340799512;587.6797145555682;4430.291153415503;4674.182018146811;4681.789319480631;4684.461286952044;4705.95294047062;107651;108317;109026;109597;110126;96177;96657.84612312175;98048;98061.03877922545;99502.00497995073;66355;79848;80598.98502537483;82400.17599824045;84169.57915210385;7787.9096926431475;8842.734717958472;9622.663206787729;9918.40489570628;10065.446400447752;36416.27167456689;38487;38728;39659.80170099131;39865.601343986185;69616;72438;72458.63770681113;74361;76038.6198069006;12901.677458063248;12942.547010854574;13078.476860925457;13230.338483075615;13549.72900541964;14290;14407.279636018367;14416.783748243575;14626.634334141307;14751.409943602135;13394.89153358693;13422.865771342162;13476.865231347561;13508.797223865655;13750.106243094167;13361.465541378624;13536.458541658223;13597.796033059314;13619.046666733233;13700.794488082487;11821.091047063877;12744.74510509803;12768.872311276768;12776.808347874603;13345.199288042748;9391.906080939376;9489.383190092622;9556.378835375679;9901.455419951972;10085.495725213857;8466.464776845358;9773;10660.200484963503;10944;11279.492422840827 05:22:05 INFO - PID 1637 | __end_tp_report 05:22:05 INFO - PID 1637 | __start_cc_report 05:22:05 INFO - PID 1637 | _x_x_mozilla_cycle_collect,431 05:22:05 INFO - PID 1637 | __end_cc_report 05:22:05 INFO - PID 1637 | __startTimestamp1491308525503__endTimestamp 05:22:05 INFO - PID 1637 | 05:22:05 INFO - PID 1637 | ------- Summary: start ------- 05:22:05 INFO - PID 1637 | Number of tests: 6 05:22:05 INFO - PID 1637 | 05:22:05 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6012.68 Median:6302.65 stddev:745.07 (11.8%) stddev-sans-first:706.75 05:22:05 INFO - PID 1637 | Values: 2920.0 2986.0 3136.5 3498.5 3572.5 5993.4 6151.7 6186.3 6213.3 6241.0 6043.2 6052.5 6112.5 6112.9 6114.9 5313.2 5357.3 5377.5 5379.1 5435.3 5264.3 5361.2 5408.6 5433.1 5445.5 5374.4 5392.4 5424.6 5433.5 5476.2 5173.8 5274.4 5277.7 5283.5 5284.6 5215.6 5303.6 5310.1 5344.9 5376.8 4642.0 5874.7 6077.9 6132.8 6178.3 6295.6 6447.2 6467.6 6475.8 6483.1 5816.7 6371.7 6542.0 6577.7 6589.2 6196.2 6201.7 6230.0 6233.3 6240.2 6553.6 6669.5 6672.5 6677.7 6685.1 6266.4 6354.8 6379.1 6385.5 6691.5 6644.1 6657.0 6665.3 6672.5 6723.5 6457.4 6471.7 6508.7 6519.9 6539.7 6255.2 6337.7 6355.5 6424.6 6517.9 5228.5 5275.7 5276.3 5280.3 5283.0 4081.2 4725.2 4730.1 4748.6 4790.4 5445.9 5896.9 6136.3 6246.8 6321.4 6527.1 6556.5 6583.1 6597.1 6602.7 6315.3 6315.4 6318.2 6328.1 6351.4 5640.5 5695.5 5819.2 5819.5 5855.4 6556.8 6700.5 6745.4 6751.5 6777.9 6572.7 6599.8 6628.6 6641.0 6663.2 6443.7 6501.4 6717.9 6796.2 6798.5 6126.3 6489.9 6496.5 6512.6 6523.6 6358.1 6500.7 6522.6 6556.3 6557.7 4633.4 6359.7 6384.8 6413.4 6700.4 6401.4 6401.6 6404.5 6405.6 6415.4 6009.9 6048.0 6143.5 6144.8 6268.0 6354.7 6383.3 6396.3 6398.0 6399.7 6240.2 6275.2 6302.7 6324.1 6336.6 05:22:05 INFO - PID 1637 | 05:22:05 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:52812.08 Median:179144.94 stddev:111500.70 (62.2%) stddev-sans-first:111744.82 05:22:05 INFO - PID 1637 | Values: 692.4 1010.9 1039.7 1190.5 1214.6 29887.9 36414.5 36624.1 36665.8 36778.0 35733.1 35939.8 36151.6 36299.1 36319.6 8246.3 8491.4 8496.0 8500.7 8502.3 7626.9 8071.0 8389.1 8424.5 8438.1 8459.9 8473.2 8529.2 8537.6 8550.6 8443.1 8473.3 8485.0 8493.7 8522.7 8318.0 8380.6 8386.4 8390.2 8412.9 36028.6 36138.9 36297.8 36355.0 36449.6 2961.6 2984.1 2993.3 3014.0 3036.9 26006.3 26145.8 26425.5 26630.0 26748.0 2813.1 2867.8 2891.5 2893.2 2893.9 264734.0 321915.0 324595.0 324704.0 325602.4 37381.6 44274.8 44406.0 44835.3 44920.3 44704.8 44723.9 44814.8 44831.6 44909.3 13141.3 13171.5 13199.9 13203.3 13322.0 10708.4 10730.6 10911.0 10918.0 10962.9 10741.3 11977.6 12360.3 12373.9 12382.9 5432.9 5466.4 5475.4 5483.8 5490.9 793.3 919.7 929.9 933.6 971.0 22851.7 23078.9 23157.5 23169.8 23358.6 1027.8 1029.3 1062.4 1063.1 1069.4 11730.6 11878.1 11880.6 11937.9 12009.8 474919.0 478831.0 481363.0 482559.0 485863.6 48922.8 49378.8 49497.3 49511.8 49631.3 1341.2 1395.3 1414.8 1425.1 1433.5 22184.4 22837.0 22872.5 22993.9 23065.4 33861.0 35676.6 35724.1 35918.3 35948.8 435669.0 438326.0 443897.0 446495.0 448167.0 24479.8 30423.4 30744.8 30939.4 30946.2 29532.6 33170.5 38597.8 39944.4 40035.0 27430.7 28487.6 29136.3 29140.9 29346.3 26462.5 28225.4 28563.9 29045.7 29302.6 32687.5 32934.7 33051.3 33102.3 33594.0 26740.9 28627.0 29454.9 29535.9 29703.0 21508.6 22162.7 23004.0 23259.0 23490.9 05:22:05 INFO - PID 1637 | 05:22:05 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:40430.10 Median:21406.02 stddev:115665.91 (540.3%) stddev-sans-first:115951.36 05:22:05 INFO - PID 1637 | Values: 413.9 418.4 424.2 430.3 438.9 18990.6 20648.7 21354.8 21403.7 21408.4 19317.1 19479.9 19614.3 20015.4 20148.3 5865.3 6078.5 6115.5 6120.4 6124.4 5939.1 5975.7 6035.5 6037.4 6279.1 5281.3 5467.9 6019.6 6203.3 6228.4 5852.3 6138.4 6156.4 6214.1 6232.0 5320.0 5330.2 5330.8 5340.4 5353.1 22535.9 22976.7 23772.2 24936.0 25486.7 1947.1 1992.4 2049.9 2052.7 2059.5 15315.3 16033.8 16088.7 16357.6 16804.6 1756.7 1879.5 1884.8 1945.4 1955.0 146061.0 158713.0 159103.0 159176.0 161042.2 11866.8 12001.6 12099.4 12205.8 12248.7 13025.4 15611.3 15637.1 15656.9 15743.6 7198.9 7280.4 8507.5 8571.4 8604.4 6666.0 8135.0 8191.6 8225.3 8276.9 6422.7 7365.7 7423.7 7450.7 7500.3 2080.1 2081.4 2082.2 2094.2 2221.0 874.7 879.1 880.9 911.0 911.7 18489.4 19766.7 20254.7 20341.7 20740.2 766.6 804.9 875.4 920.3 936.7 8517.8 10025.7 10400.7 10405.9 10520.1 601163.0 695610.0 698385.0 705363.0 718698.0 153127.0 166380.0 169291.0 169920.0 172722.0 1091.5 1133.5 1166.4 1167.8 1174.5 20488.0 20730.3 20842.9 20988.5 21118.8 64796.7 65281.7 65483.0 66158.0 66300.0 73095.0 89312.7 89911.7 90708.5 91069.0 8478.4 8515.9 8572.7 8678.9 8714.0 13323.4 13405.9 13430.3 13743.6 13813.5 11567.2 11710.8 11791.6 11862.5 11996.2 9136.8 10438.8 10602.6 10859.9 10961.1 11706.6 11804.7 11858.6 11937.0 12066.7 7455.6 7516.7 8197.2 8553.4 8713.8 10976.2 11421.7 11454.0 11609.7 11693.2 05:22:05 INFO - PID 1637 | 05:22:05 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:6663.69 Median:3826.56 stddev:4077.55 (106.6%) stddev-sans-first:4068.02 05:22:05 INFO - PID 1637 | Values: 1161.8 1342.6 1376.5 1392.7 1397.3 9628.9 9982.8 10755.5 10956.7 11013.6 10649.6 10766.5 10838.2 10867.5 10868.2 1974.5 2055.2 2439.4 2444.3 2449.9 1925.8 2016.9 2329.0 2397.2 2411.6 1999.7 2369.4 2383.2 2396.3 2411.7 2304.6 2339.2 2342.6 2353.2 2355.8 2000.5 2082.4 2334.5 2397.5 2422.8 10757.3 10776.4 10788.3 10857.8 10867.9 5237.9 6039.2 6279.1 6286.8 6288.5 3791.7 3861.4 3920.1 3949.9 4018.3 1208.4 1559.8 1588.4 1622.7 1668.7 12137.9 12349.8 13741.5 14758.8 14916.3 5323.2 5460.7 5512.6 5521.9 5571.1 12580.4 12668.8 12731.2 12746.7 12772.0 6253.4 6269.5 6304.1 6308.2 6328.9 5502.8 5596.7 5612.3 5622.6 5687.0 4261.0 4379.8 4425.7 4428.1 4431.4 1182.9 1355.4 1396.7 1396.7 1407.9 605.3 651.3 721.3 747.8 755.1 6185.2 6374.4 6520.7 6584.5 6695.3 749.9 852.0 876.3 876.5 882.1 3257.7 3556.4 3624.1 4029.4 4031.5 11128.5 12946.7 13506.5 13506.7 13688.5 13076.3 13237.6 13397.0 13424.7 13529.7 9907.4 9915.8 10075.0 10129.7 10150.5 5214.6 5859.7 6202.5 6436.9 6717.6 11924.8 12022.7 12238.6 12366.4 12415.8 9871.4 12418.0 12666.5 12722.4 12845.3 8145.5 9879.5 9917.5 9975.7 10082.4 9736.3 9792.8 9944.0 9985.2 10046.0 8352.1 8495.2 8524.2 8668.0 8733.0 8116.8 8166.3 8269.8 8364.2 8543.5 7577.0 7672.6 7716.0 7748.9 7765.2 5772.1 5791.0 5845.7 5977.3 6028.2 5763.1 5786.9 5906.8 5940.6 6066.2 05:22:05 INFO - PID 1637 | 05:22:05 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5261.56 Median:5938.24 stddev:1615.92 (27.2%) stddev-sans-first:1578.54 05:22:05 INFO - PID 1637 | Values: 389.9 399.3 401.1 401.7 414.1 5764.3 6081.8 6260.7 6314.2 6531.0 4048.6 6045.7 6084.6 6094.0 6237.8 4619.4 4630.6 4639.5 4652.0 4661.7 4239.2 4659.7 4664.6 4665.0 4684.4 4102.5 4222.7 4300.0 4478.4 4577.4 4182.8 4212.3 4598.6 4611.3 4632.0 3674.8 3847.0 4059.4 4088.0 4096.9 5452.8 5771.2 5920.3 6018.9 6029.7 5473.3 5501.4 5525.1 5547.8 5581.4 6038.4 6285.3 6492.4 6569.9 6606.3 3797.9 4669.1 4673.6 4673.7 4680.6 6369.6 6795.0 6898.0 6900.0 6930.9 4643.2 4763.6 4791.9 4803.2 4804.5 4879.4 4889.9 4892.9 4908.6 4931.7 5073.4 5395.4 5406.4 5530.6 5539.3 6046.9 6608.1 6609.5 6632.0 6643.6 1214.7 1349.2 1466.5 1533.8 1541.3 839.9 919.3 956.0 1020.5 1029.4 4665.9 5284.9 5967.3 6194.0 6198.2 4985.7 5749.7 6030.3 6439.9 6688.1 5956.2 6103.6 6138.9 6161.7 6179.2 3326.5 3334.8 3512.4 3580.9 3595.6 5615.0 6727.2 6957.1 7001.8 7048.4 5568.9 6046.5 6713.7 6856.4 6974.8 6026.2 6471.8 6548.0 6990.7 7019.2 6206.4 6730.4 6762.3 6777.6 6846.9 5670.6 6328.6 6393.2 6664.5 6665.3 5766.6 6198.5 6216.0 6926.8 6962.6 4400.1 4403.9 4408.6 4420.5 4420.9 5423.2 5546.0 5734.3 6447.4 6775.0 6206.6 6501.7 6514.8 6531.4 6537.3 5330.7 6008.3 6153.7 6537.7 6542.5 6200.0 6386.3 6516.1 6529.8 6601.6 5524.7 6222.4 6333.2 6419.6 6663.9 5143.8 6428.7 6432.6 6448.6 6472.0 05:22:05 INFO - PID 1637 | 05:22:05 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:20890.80 Median:21919.44 stddev:31133.30 (142.0%) stddev-sans-first:31183.63 05:22:05 INFO - PID 1637 | Values: 673.4 677.2 680.8 689.1 693.3 14481.6 17752.8 18425.0 18574.4 18581.3 16246.3 16376.8 16428.0 16468.0 16777.0 814.6 827.5 837.4 839.1 852.1 815.0 823.1 830.1 834.6 841.0 837.9 859.1 876.8 893.2 895.5 859.7 873.6 875.0 880.3 880.6 621.7 643.8 647.1 659.9 665.0 15049.5 15294.8 15472.0 15527.0 15763.8 11503.9 13830.5 13902.1 14119.6 14414.4 11372.7 11496.5 11589.3 12087.1 12095.0 2238.7 2756.5 2791.1 2797.3 2830.9 97278.0 112480.0 114913.0 119450.0 121100.4 9136.6 10918.4 10975.3 11050.8 11542.6 17686.0 20589.4 20709.3 20804.3 20904.8 6588.0 7759.9 7776.0 7934.5 8073.0 4092.2 4226.4 4239.6 4268.1 4269.4 3878.7 3924.8 3932.5 3961.8 3973.3 683.4 695.3 732.3 742.4 802.8 517.6 541.3 555.3 574.4 590.2 9992.4 10201.2 10248.8 10378.0 10482.9 533.6 565.7 570.6 584.6 587.7 4430.3 4674.2 4681.8 4684.5 4706.0 107651.0 108317.0 109026.0 109597.0 110126.0 96177.0 96657.8 98048.0 98061.0 99502.0 66355.0 79848.0 80599.0 82400.2 84169.6 7787.9 8842.7 9622.7 9918.4 10065.4 36416.3 38487.0 38728.0 39659.8 39865.6 69616.0 72438.0 72458.6 74361.0 76038.6 12901.7 12942.5 13078.5 13230.3 13549.7 14290.0 14407.3 14416.8 14626.6 14751.4 13394.9 13422.9 13476.9 13508.8 13750.1 13361.5 13536.5 13597.8 13619.0 13700.8 11821.1 12744.7 12768.9 12776.8 13345.2 9391.9 9489.4 9556.4 9901.5 10085.5 8466.5 9773.0 10660.2 10944.0 11279.5 05:22:05 INFO - PID 1637 | -------- Summary: end -------- 05:22:05 INFO - PID 1637 | 05:22:06 INFO - TEST-INFO | 1637: exit 0 05:22:06 INFO - TEST-OK | dromaeo_css | took 1116666ms 05:22:06 INFO - TEST-START | kraken 05:22:06 INFO - operating with platform_type : mac_ 05:22:06 INFO - Initialising browser for kraken test... 05:22:06 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpBer9rn/profile http://localhost:49242/getInfo.html) 05:22:08 INFO - PID 1649 | 2017-04-04 05:22:08.871 plugin-container[1651:10044] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 05:22:08 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:22:08 INFO - PID 1649 | 2017-04-04 05:22:08.872 plugin-container[1651:10044] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431f, name = 'com.apple.CFPasteboardClient' 05:22:08 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:22:08 INFO - PID 1649 | 2017-04-04 05:22:08.872 plugin-container[1651:10044] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:22:08 INFO - PID 1649 | __metrics Screen width/height:1600/1200 05:22:08 INFO - PID 1649 | colorDepth:24 05:22:08 INFO - PID 1649 | Browser inner width/height: 1024/689 05:22:08 INFO - PID 1649 | __metrics 05:22:09 ERROR - PID 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 05:22:09 INFO - TEST-INFO | 1649: exit 0 05:22:09 INFO - Browser initialized. 05:22:09 INFO - Running cycle 1/1 for kraken test... 05:22:09 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpBer9rn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:22:11 INFO - PID 1652 | 2017-04-04 05:22:11.802 plugin-container[1653:10165] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 05:22:11 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:22:11 INFO - PID 1652 | 2017-04-04 05:22:11.804 plugin-container[1653:10165] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.CFPasteboardClient' 05:22:11 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:22:11 INFO - PID 1652 | 2017-04-04 05:22:11.804 plugin-container[1653:10165] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:22:21 INFO - PID 1652 | RSS: Main: 228487168 05:22:21 INFO - PID 1652 | 05:22:24 INFO - PID 1652 | 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) 05:22:25 INFO - PID 1652 | RSS: Main: 243785728 05:22:25 INFO - PID 1652 | 05:22:28 INFO - PID 1652 | 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) 05:22:29 INFO - PID 1652 | RSS: Main: 251736064 05:22:29 INFO - PID 1652 | 05:22:33 INFO - PID 1652 | 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) 05:22:33 INFO - PID 1652 | RSS: Main: 254062592 05:22:33 INFO - PID 1652 | 05:22:35 INFO - PID 1652 | 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) 05:22:36 INFO - PID 1652 | RSS: Main: 255025152 05:22:36 INFO - PID 1652 | 05:22:38 INFO - PID 1652 | 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) 05:22:38 INFO - PID 1652 | RSS: Main: 256708608 05:22:38 INFO - PID 1652 | 05:22:42 INFO - PID 1652 | 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) 05:22:42 INFO - PID 1652 | RSS: Main: 262397952 05:22:42 INFO - PID 1652 | 05:22:46 INFO - PID 1652 | 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) 05:22:46 INFO - PID 1652 | RSS: Main: 262795264 05:22:46 INFO - PID 1652 | 05:22:50 INFO - PID 1652 | 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) 05:22:50 INFO - PID 1652 | RSS: Main: 262483968 05:22:50 INFO - PID 1652 | 05:22:52 INFO - PID 1652 | 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) 05:22:52 INFO - PID 1652 | RSS: Main: 262176768 05:22:52 INFO - PID 1652 | 05:22:54 INFO - PID 1652 | 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) 05:22:55 INFO - PID 1652 | RSS: Main: 261922816 05:22:55 INFO - PID 1652 | 05:22:57 INFO - PID 1652 | 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) 05:22:58 INFO - PID 1652 | RSS: Main: 261955584 05:22:58 INFO - PID 1652 | 05:23:01 INFO - PID 1652 | 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) 05:23:02 INFO - PID 1652 | RSS: Main: 262397952 05:23:02 INFO - PID 1652 | 05:23:05 INFO - PID 1652 | 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) 05:23:06 INFO - PID 1652 | RSS: Main: 259661824 05:23:06 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | 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) 05:23:08 INFO - PID 1652 | RSS: Main: 262975488 05:23:08 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | __start_tp_report 05:23:08 INFO - PID 1652 | _x_x_mozilla_page_load 05:23:08 INFO - PID 1652 | _x_x_mozilla_page_load_details 05:23:08 INFO - PID 1652 | |i|pagename|runs| 05:23:08 INFO - PID 1652 | |0;ai-astar;120;185;153;121;123;125;126;167;179;167 05:23:08 INFO - PID 1652 | |1;audio-beat-detection;197;105;148;200;154;118;111;116;147;157 05:23:08 INFO - PID 1652 | |2;audio-dft;227;223;296;237;217;209;242;211;212;213 05:23:08 INFO - PID 1652 | |3;audio-fft;84;60;61;87;61;62;58;65;66;86 05:23:08 INFO - PID 1652 | |4;audio-oscillator;95;71;88;73;72;75;85;73;73;86 05:23:08 INFO - PID 1652 | |5;imaging-gaussian-blur;93;88;112;107;119;141;99;142;139;96 05:23:08 INFO - PID 1652 | |6;imaging-darkroom;153;105;124;100;99;99;99;100;158;100 05:23:08 INFO - PID 1652 | |7;imaging-desaturate;163;147;112;101;103;106;101;101;105;158 05:23:08 INFO - PID 1652 | |8;json-parse-financial;51;52;53;53;53;53;55;53;52;53 05:23:08 INFO - PID 1652 | |9;json-stringify-tinderbox;59;61;75;69;69;87;87;71;61;60 05:23:08 INFO - PID 1652 | |10;stanford-crypto-aes;88;88;88;90;90;90;100;86;89;91 05:23:08 INFO - PID 1652 | |11;stanford-crypto-ccm;153;150;175;153;151;183;172;157;209;157 05:23:08 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;240;199;196;197;210;194;255;265;196;204 05:23:08 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;76;87;98;103;72;72;73;71;69;72 05:23:08 INFO - PID 1652 | __end_tp_report 05:23:08 INFO - PID 1652 | __start_cc_report 05:23:08 INFO - PID 1652 | _x_x_mozilla_cycle_collect,1025 05:23:08 INFO - PID 1652 | __end_cc_report 05:23:08 INFO - PID 1652 | __startTimestamp1491308588515__endTimestamp 05:23:08 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | ------- Summary: start ------- 05:23:08 INFO - PID 1652 | Number of tests: 14 05:23:08 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:146.60 Median:160.00 stddev:26.26 (16.4%) stddev-sans-first:26.03 05:23:08 INFO - PID 1652 | Values: 120.0 185.0 153.0 121.0 123.0 125.0 126.0 167.0 179.0 167.0 05:23:08 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:145.30 Median:151.00 stddev:33.82 (22.4%) stddev-sans-first:30.25 05:23:08 INFO - PID 1652 | Values: 197.0 105.0 148.0 200.0 154.0 118.0 111.0 116.0 147.0 157.0 05:23:08 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:228.70 Median:225.00 stddev:26.19 (11.6%) stddev-sans-first:27.77 05:23:08 INFO - PID 1652 | Values: 227.0 223.0 296.0 237.0 217.0 209.0 242.0 211.0 212.0 213.0 05:23:08 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:69.00 Median:65.50 stddev:11.75 (17.9%) stddev-sans-first:11.14 05:23:08 INFO - PID 1652 | Values: 84.0 60.0 61.0 87.0 61.0 62.0 58.0 65.0 66.0 86.0 05:23:08 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:79.10 Median:80.00 stddev:8.56 (10.7%) stddev-sans-first:6.87 05:23:08 INFO - PID 1652 | Values: 95.0 71.0 88.0 73.0 72.0 75.0 85.0 73.0 73.0 86.0 05:23:08 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:115.00 stddev:20.76 (18.1%) stddev-sans-first:20.64 05:23:08 INFO - PID 1652 | Values: 93.0 88.0 112.0 107.0 119.0 141.0 99.0 142.0 139.0 96.0 05:23:08 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:113.70 Median:155.50 stddev:23.33 (15.0%) stddev-sans-first:19.95 05:23:08 INFO - PID 1652 | Values: 153.0 105.0 124.0 100.0 99.0 99.0 99.0 100.0 158.0 100.0 05:23:08 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:119.70 Median:109.00 stddev:25.56 (23.4%) stddev-sans-first:21.78 05:23:08 INFO - PID 1652 | Values: 163.0 147.0 112.0 101.0 103.0 106.0 101.0 101.0 105.0 158.0 05:23:08 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:1.03 (1.9%) stddev-sans-first:0.87 05:23:08 INFO - PID 1652 | Values: 51.0 52.0 53.0 53.0 53.0 53.0 55.0 53.0 52.0 53.0 05:23:08 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.00 stddev:10.48 (15.0%) stddev-sans-first:10.35 05:23:08 INFO - PID 1652 | Values: 59.0 61.0 75.0 69.0 69.0 87.0 87.0 71.0 61.0 60.0 05:23:08 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:89.50 stddev:3.80 (4.2%) stddev-sans-first:3.96 05:23:08 INFO - PID 1652 | Values: 88.0 88.0 88.0 90.0 90.0 90.0 100.0 86.0 89.0 91.0 05:23:08 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:166.00 Median:164.50 stddev:18.96 (11.5%) stddev-sans-first:19.52 05:23:08 INFO - PID 1652 | Values: 153.0 150.0 175.0 153.0 151.0 183.0 172.0 157.0 209.0 157.0 05:23:08 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:215.60 Median:207.00 stddev:27.10 (13.1%) stddev-sans-first:27.27 05:23:08 INFO - PID 1652 | Values: 240.0 199.0 196.0 197.0 210.0 194.0 255.0 265.0 196.0 204.0 05:23:08 INFO - PID 1652 | 05:23:08 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.30 Median:72.50 stddev:12.28 (16.9%) stddev-sans-first:12.96 05:23:08 INFO - PID 1652 | Values: 76.0 87.0 98.0 103.0 72.0 72.0 73.0 71.0 69.0 72.0 05:23:08 INFO - PID 1652 | -------- Summary: end -------- 05:23:08 INFO - PID 1652 | 05:23:09 INFO - TEST-INFO | 1652: exit 0 05:23:09 INFO - TEST-OK | kraken | took 63197ms 05:23:09 INFO - SUITE-END | took 1179s 05:23:09 INFO - Completed test suite (00:19:40) 05:23:09 INFO - javascript benchmark 05:23:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7525.210640872214, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2920.0, 2985.9552106718415, 3136.5138403547453, 3498.4577390504483, 3572.481990111434, 5993.400659934005, 6151.69241537923, 6186.257649082111, 6213.285472170702, 6241.032639940805, 6043.153958445821, 6052.455279024887, 6112.510999120071, 6112.8986537897235, 6114.908276375858, 5313.22958171065, 5357.303550538424, 5377.462253774611, 5379.112446446332, 5435.320584926884, 5264.315638966929, 5361.195820626917, 5408.567314614841, 5433.1035379162395, 5445.455454454543, 5374.381946076212, 5392.379876314235, 5424.566034717213, 5433.510984011418, 5476.2333273341765, 5173.793048278102, 5274.367075950872, 5277.70972596507, 5283.471652834724, 5284.5772338212855, 5215.582753379758, 5303.628745987782, 5310.070737620939, 5344.946550534483, 5376.838694839161, 4641.976790116028, 5874.73563689635, 6077.939220607782, 6132.754689812402, 6178.289496707846, 6295.590786598902, 6447.194100737408, 6467.579607325509, 6475.805725828232, 6483.124778154983, 5816.651000939957, 6371.745130194787, 6541.967290163567, 6577.736890524373, 6589.242237142694, 6196.194494715748, 6201.658908760016, 6230.034344676537, 6233.283172435137, 6240.219972503437, 6553.63954982471, 6669.499787515859, 6672.466202703772, 6677.666116694146, 6685.064091027163, 6266.436020758154, 6354.841128971812, 6379.106925030499, 6385.457236134887, 6691.464682825363, 6644.103046088813, 6657.0, 6665.266820649725, 6672.466202703772, 6723.495737819683, 6457.418832305115, 6471.6764161791725, 6508.707108180049, 6519.902201466982, 6539.673016349164, 6255.155554000152, 6337.651429171347, 6355.523335749745, 6424.614523128627, 6517.8696426072165, 5228.503292187236, 5275.657082289639, 5276.34045744282, 5280.339957505312, 5283.0, 4081.2449696806184, 4725.243960966229, 4730.053989202104, 4748.620110391161, 4790.377250957354, 5445.863853403617, 5896.882062358729, 6136.274005700925, 6246.781362652286, 6321.431071203614, 6527.053577231275, 6556.475481961432, 6583.144191255106, 6597.109390232353, 6602.735890564323, 6315.273743519462, 6315.368463153648, 6318.210223722035, 6328.082428047907, 6351.396617321347, 5640.5487560995025, 5695.515881150066, 5819.185314056035, 5819.476247137778, 5855.3851845556455, 6556.770513032021, 6700.5309628327495, 6745.4266387355665, 6751.459883209233, 6777.864442711217, 6572.671366431564, 6599.799097437643, 6628.602283862983, 6641.0, 6663.233728121328, 6443.71003304843, 6501.382368674968, 6717.899231511434, 6796.184457865088, 6798.456123510206, 6126.30393285589, 6489.9026514603265, 6496.480281577557, 6512.5766825156215, 6523.575967562093, 6358.141650877257, 6500.70746816385, 6522.608643481406, 6556.3443655634055, 6557.704903279268, 4633.443986721615, 6359.682015899094, 6384.808455746335, 6413.390727880844, 6700.363465470772, 6401.423871851555, 6401.583897046677, 6404.519661025349, 6405.551611387158, 6415.422611964946, 6009.90985135232, 6048.0, 6143.511877689543, 6144.801763656044, 6267.96866015667, 6354.682265886595, 6383.32975037633, 6396.296407395276, 6397.96801015992, 6399.6800159992745, 6240.157578726814, 6275.246970363585, 6302.65335406557, 6324.051392291193, 6336.619802811846], "value": 5960.041392555025, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.3595674001549, 1010.8938561451052, 1039.656913218638, 1190.4702407428692, 1214.5870404062616, 29887.850560747193, 36414.453783193276, 36624.08439789007, 36665.816670916676, 36777.97721125337, 35733.106672333204, 35939.82030089846, 36151.638483615156, 36299.092522686944, 36319.636803631955, 8246.299064579503, 8491.363147763943, 8495.957520212422, 8500.74497765068, 8502.319814414832, 7626.923730762677, 8071.0, 8389.07720150783, 8424.4945303282, 8438.071812100665, 8459.873101903477, 8473.237408633222, 8529.232369086782, 8537.615807288656, 8550.572471376438, 8443.071262161156, 8473.279771219453, 8485.0, 8493.702720404786, 8522.659093636246, 8318.04342500614, 8380.622871970776, 8386.371022173362, 8390.160983901622, 8412.915870841274, 36028.63971360305, 36138.91583252507, 36297.81851090754, 36355.0, 36449.63550364489, 2961.6297962754657, 2984.1346009657173, 2993.266649670823, 3014.0, 3036.893708720206, 26006.34984125412, 26145.82343794534, 26425.471490570275, 26630.0, 26748.0, 2813.08574713219, 2867.7992540522173, 2891.479533683936, 2893.1609833148364, 2893.9276518087004, 264734.0, 321915.0, 324595.0, 324704.0, 325602.3719881409, 37381.62618373836, 44274.778626106665, 44406.0, 44835.32747008798, 44920.32619510645, 44704.77647611806, 44723.881902952686, 44814.77592612081, 44831.55168448339, 44909.32636010463, 13141.342932853318, 13171.538996135088, 13199.934000329937, 13203.273819939996, 13322.0008499361, 10708.411037393018, 10730.570777168981, 10911.0, 10918.01737843582, 10962.945185274022, 10741.301815381976, 11977.580784672495, 12360.320182389874, 12373.93813030929, 12382.876171238173, 5432.91850622241, 5466.426025267369, 5475.4250803665045, 5483.835484935458, 5490.945090549123, 793.2503783924252, 919.7240827751648, 929.8930622978309, 933.5892207428709, 970.9854352184723, 22851.657225141636, 23078.8846055772, 23157.484290563178, 23169.768302317098, 23358.64962025571, 1027.8098551767946, 1029.3103620574234, 1062.3785085724783, 1063.0751246415668, 1069.4225118435957, 11730.589429369931, 11878.109141814226, 11880.643580692593, 11937.940310298567, 12009.759804803854, 474919.0, 478831.0, 481363.0, 482559.0, 485863.5706821443, 48922.75538622355, 49378.765530862016, 49497.25754113691, 49511.752441238285, 49631.25553116706, 1341.2086868747485, 1395.3093218856732, 1414.8302203735618, 1425.123549017347, 1433.4839457795185, 22184.445388865406, 22837.0, 22872.542549149257, 22993.88503057474, 23065.423364416023, 33860.98417047493, 35676.57293708326, 35724.10689732777, 35918.28163436769, 35948.82025589855, 435669.0, 438326.0, 443897.0, 446495.0, 448167.0, 24479.755202447748, 30423.391532169677, 30744.84627576848, 30939.381212376076, 30946.226344341572, 29532.557011644414, 33170.50244246387, 38597.842064738106, 39944.40083398693, 40034.99912502211, 27430.725692742817, 28487.572686409305, 29136.27159320949, 29140.854295728386, 29346.266343341587, 26462.470750584496, 28225.43549129047, 28563.857180713963, 29045.7095429043, 29302.560461592668, 32687.50968735423, 32934.67065329412, 33051.33897322088, 33102.33795324128, 33594.0, 26740.8662956684, 28627.0, 29454.93971810166, 29535.85232073826, 29703.0, 21508.569828603653, 22162.667559986934, 23004.0, 23259.0, 23490.882545587163], "value": 16872.457825241396, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.8903190654475, 418.3682639214785, 424.1813300330362, 430.32224246811273, 438.94732632084134, 18990.620187596258, 20648.690269645947, 21354.786452135475, 21403.67894481584, 21408.357749267507, 19317.130729117187, 19479.902600486977, 19614.313499027503, 20015.399538013848, 20148.294809681665, 5865.296164460271, 6078.513718902499, 6115.48018418435, 6120.357362476942, 6124.418180272867, 5939.079442686369, 5975.731092100866, 6035.5473339499495, 6037.396260373971, 6279.120923070774, 5281.287026251464, 5467.863303417406, 6019.578629495937, 6203.255609326887, 6228.439440450358, 5852.268466441652, 6138.355472675349, 6156.3843615638525, 6214.067889816519, 6232.0, 5319.973400132974, 5330.173823057432, 5330.760115794798, 5340.385855626614, 5353.143497040455, 22535.88732056329, 22976.73627950462, 23772.167974120995, 24936.00255989758, 25486.74513254862, 1947.094601010537, 1992.3624440179149, 2049.8565100442975, 2052.702358158074, 2059.5468996820678, 15315.31081101353, 16033.759493607606, 16088.678226435522, 16357.59106022347, 16804.579885502837, 1756.745271935575, 1879.4643526594991, 1884.7926728059902, 1945.3872030310406, 1954.9736388396063, 146061.0, 158713.0, 159103.0, 159176.0, 161042.1947890253, 11866.76266474683, 12001.63995080149, 12099.395030248452, 12205.75588488243, 12248.693782655326, 13025.413856376486, 15611.297491612904, 15637.139957302457, 15656.92171539135, 15743.606409839616, 7198.8841729532005, 7280.381167600814, 8507.53208573522, 8571.4000019998, 8604.397692161614, 6666.0, 8134.959325203455, 8191.631376588068, 8225.259726624512, 8276.917230827736, 6422.68334991335, 7365.668544915492, 7423.665935032936, 7450.739224127129, 7500.287472690195, 2080.147139672727, 2081.39639504542, 2082.2087606709656, 2094.193735411847, 2220.977790222077, 874.6588830356167, 879.098923603299, 880.8546589812737, 910.9544522773834, 911.6945823149186, 18489.352872649393, 19766.70349944752, 20254.69617955732, 20341.694874576893, 20740.170393184104, 766.5707203965796, 804.8631732605471, 875.4003507597189, 920.2683866326287, 936.7189843047195, 8517.787055323666, 10025.749356266151, 10400.739981500523, 10405.895941040493, 10520.053195212467, 601163.0, 695610.0, 698385.0, 705363.0, 718698.0, 153127.0, 166380.0, 169291.0, 169920.0, 172722.0, 1091.4815462655333, 1133.4672703829021, 1166.445938179358, 1167.8496680769435, 1174.4714878304626, 20488.0, 20730.274440394514, 20842.895785520974, 20988.47528811792, 21118.78881211168, 64796.67601661962, 65281.67359163174, 65483.0, 66158.0, 66300.0, 73095.0, 89312.6603100967, 89911.65132522887, 90708.54645726993, 91069.0, 8478.448900821428, 8515.872261915953, 8572.742817715478, 8678.869816952625, 8713.956430217808, 13323.400446979715, 13405.865941340462, 13430.261335626243, 13743.587692369245, 13813.51652692151, 11567.190296679488, 11710.765784684429, 11791.587294444653, 11862.466189021687, 11996.160268781101, 9136.771580710536, 10438.791224175626, 10602.628907988183, 10859.945700271765, 10961.12311015101, 11706.590269340531, 11804.735782237263, 11858.644240672793, 11936.98535624447, 12066.698332541757, 7455.552666840007, 7516.736914207976, 8197.180281971756, 8553.401261911606, 8713.823633809354, 10976.23166378346, 11421.657350279505, 11453.96914277719, 11609.6517104487, 11693.181477296508], "value": 10171.828416056971, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1161.8489596352472, 1342.5636668082875, 1376.4769387632698, 1392.7326133218767, 1397.3222986851379, 9628.903710962888, 9982.800343993125, 10755.516001779919, 10956.671299860996, 11013.559457621706, 10649.627263045795, 10766.515506802212, 10838.241323107386, 10867.510962006709, 10868.239223254375, 1974.5359840437486, 2055.2498338106634, 2439.4389290463205, 2444.278937713379, 2449.9020039198413, 1925.7785354684252, 2016.889071101096, 2329.010170677464, 2397.1729753235104, 2411.626197939322, 1999.7000449932555, 2369.4194922244073, 2383.165891937825, 2396.341006223294, 2411.7347091819884, 2304.631258998569, 2339.19297842245, 2342.590046741822, 2353.176388264111, 2355.8115350771895, 2000.4998750312423, 2082.427332483564, 2334.5447637710654, 2397.484540823714, 2422.794062504689, 10757.30077544957, 10776.40729759863, 10788.298760580537, 10857.782844343068, 10867.913208679149, 5237.869053273661, 6039.245094363205, 6279.120923070774, 6286.842828929268, 6288.465480434169, 3791.7345785795005, 3861.38217885137, 3920.0787814863484, 3949.9012524686827, 4018.2967980603275, 1208.3776854919724, 1559.8128224613006, 1588.4122874536495, 1622.7403615421476, 1668.7413450915128, 12137.93931030348, 12349.814752778715, 13741.450341986309, 14758.778618320734, 14916.328765205591, 5323.228131920927, 5460.6996615186545, 5512.586556008316, 5521.861953451196, 5571.13647384656, 12580.4338804754, 12668.80996785049, 12731.172473789175, 12746.68133296675, 12772.0, 6253.437190652863, 6269.529785266123, 6304.085907543381, 6308.243010838729, 6328.873422531524, 5502.8349149525575, 5596.664200148004, 5612.326520817528, 5622.57830662702, 5686.9715651421475, 4260.957390426118, 4379.824807007684, 4425.712328698624, 4428.13651337986, 4431.446069241345, 1182.8580570331615, 1355.3697530648296, 1396.678763884292, 1396.6927275999265, 1407.90848594841, 605.3462260758371, 651.3260919437096, 721.3255606008399, 747.8392145688595, 755.1165136789969, 6185.195924529872, 6374.426301632875, 6520.673966301666, 6584.473242140617, 6695.263521012685, 749.9437542184249, 851.9531425771518, 876.3252295732378, 876.5179151466668, 882.1443200095897, 3257.657945915692, 3556.359855226084, 3624.0939765058733, 4029.4157347184496, 4031.455753473302, 11128.499217535069, 12946.741065178643, 13506.459741610226, 13506.729865402636, 13688.452461901612, 13076.280804555841, 13237.602871913858, 13397.0, 13424.664383390493, 13529.729405411837, 9907.405555666683, 9915.752106197402, 10075.042870927104, 10129.696109116738, 10150.54322555472, 5214.608904332114, 5859.677717725395, 6202.472789812917, 6436.9034464484, 6717.555725519037, 11924.821127683264, 12022.699432514257, 12238.632841014783, 12366.381680916098, 12415.813762793385, 9871.358361706467, 12418.0, 12666.493340266656, 12722.363881805688, 12845.293508857103, 8145.470544414594, 9879.456629885426, 9917.45454000037, 9975.700728978143, 10082.445465499468, 9736.318457707892, 9792.804143917225, 9944.005599439997, 9985.1512621428, 10046.0, 8352.0812710603, 8495.235428811437, 8524.232819046316, 8667.95666021691, 8733.0, 8116.75649730509, 8166.305864001626, 8269.793255168668, 8364.205400487188, 8543.487390756325, 7577.0, 7672.61636918163, 7715.961420192863, 7748.92251077482, 7765.1846556110795, 5772.134179873053, 5790.971045144747, 5845.678487683048, 5977.282726072899, 6028.156058151774, 5763.135529670415, 5786.884262314814, 5906.811606471492, 5940.55445841555, 6066.1507388966465], "value": 5092.300878714972, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.92786334528114, 399.28328650073115, 401.09552958079524, 401.67198436843006, 414.08074075552264, 5764.250647415841, 6081.817545473632, 6260.655663938487, 6314.242290925084, 6531.0, 4048.5951404859497, 6045.697715114248, 6084.604500707451, 6093.969530152354, 6237.781677641284, 4619.422572178478, 4630.560096790817, 4639.489656137839, 4651.976740116294, 4661.696989695659, 4239.215745087154, 4659.720416775004, 4664.626829853603, 4664.97667511662, 4684.3910291662205, 4102.5076990761, 4222.6506698101, 4299.957000430002, 4478.440194975628, 4577.404937358156, 4182.8288079337835, 4212.336127229727, 4598.609118224955, 4611.262198048296, 4632.0, 3674.779513229215, 3846.9807650961848, 4059.3707975263883, 4087.97956010221, 4096.877093687194, 5452.754626041838, 5771.220885180489, 5920.289565252154, 6018.916595012896, 6029.728662210211, 5473.343198816167, 5501.449855014506, 5525.11598144295, 5547.750351234205, 5581.372108129707, 6038.396160383971, 6285.308616052231, 6492.448141907944, 6569.948808190715, 6606.306337834554, 3797.9240415191543, 4669.1090108506105, 4673.626109911199, 4673.742944138071, 4680.625549956029, 6369.649494424659, 6795.0, 6898.0, 6899.965500172517, 6930.896036559456, 4643.233866412072, 4763.595094416944, 4791.856244312677, 4803.207470767354, 4804.519548045238, 4879.365682461257, 4889.902201955941, 4892.926606100911, 4908.607311415079, 4931.704097754075, 5073.365829271341, 5395.352557693023, 5406.378266499324, 5530.5852061095575, 5539.335279766454, 6046.939530604637, 6608.116686744254, 6609.471242300604, 6631.966840165768, 6643.634600097041, 1214.7084699672016, 1349.2039696579066, 1466.4647403697786, 1533.7622668486401, 1541.3372249932636, 839.8992120945526, 919.2599957034518, 956.0057540158297, 1020.5304361719101, 1029.3926583315879, 4665.883352916137, 5284.894302114013, 5967.283925928917, 6194.008958566697, 6198.2252218472695, 4985.651004429727, 5749.712514374264, 6030.33666296707, 6439.871202575922, 6688.130543029472, 5956.195913551615, 6103.633781973096, 6138.907916381258, 6161.7227224775, 6179.196704428486, 3326.4511355626532, 3334.7999120052873, 3512.420450625669, 3580.8925732228076, 3595.6044835068124, 5614.9719251403485, 6727.226368967534, 6957.095577574984, 7001.824954376181, 7048.365647091787, 5568.9443105569235, 6046.455818976313, 6713.731450741916, 6856.41720453767, 6974.79075627732, 6026.239270976055, 6471.838204044749, 6547.967260163764, 6990.693071286463, 7019.227884932653, 6206.410391012847, 6730.384707670222, 6762.289959554373, 6777.6278302595665, 6846.863062738817, 5670.631408958405, 6328.588641738273, 6393.1688880445245, 6664.5334826561675, 6665.333466653296, 5766.59633825645, 6198.512357034369, 6215.979806562795, 6926.822440185281, 6962.582245065312, 4400.129944773523, 4403.911921761611, 4408.625266852225, 4420.535843736361, 4420.889477763081, 5423.186522021729, 5545.972270138624, 5734.319517761761, 6447.3874981878525, 6775.0, 6206.596571222856, 6501.7335957874275, 6514.837129071811, 6531.412172904462, 6537.313582074004, 5330.653507521999, 6008.309044459943, 6153.661548614869, 6537.738490460329, 6542.509311801538, 6199.9540151748915, 6386.265579458422, 6516.120323756232, 6529.771457998947, 6601.636909969998, 5524.696141712245, 6222.377762223741, 6333.20834895638, 6419.614823110628, 6663.933360666331, 5143.8456846294675, 6428.742850285937, 6432.614043157426, 6448.645324507197, 6472.0], "value": 4750.059288406877, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.4208580620663, 677.1772296659558, 680.8161796314992, 689.111046749693, 693.2582137113288, 14481.565553033399, 17752.822471775293, 18425.0, 18574.44276671702, 18581.349652762172, 16246.268917898693, 16376.754348684779, 16428.0, 16468.0, 16776.986761588596, 814.5967745965755, 827.5241736001793, 837.3970741066422, 839.08539691736, 852.0882655558555, 815.0138332617511, 823.0534884882373, 830.1366578758084, 834.5785378383907, 840.9831803363929, 837.9287760540361, 859.1194026123211, 876.7720392697912, 893.1782759860944, 895.4582477601056, 859.7162936231028, 873.5806812729893, 874.9693760718408, 880.330948479154, 880.6081293824251, 621.6674079367517, 643.7875501084677, 647.0876064748682, 659.947204223661, 664.9900251496232, 15049.54851354461, 15294.770578441332, 15472.0, 15526.990745601499, 15763.842361576351, 11503.884961150448, 13830.515931942333, 13902.096363736326, 14119.576412707635, 14414.351354189086, 11372.715682107932, 11496.482658280314, 11589.304641721523, 12087.093467989936, 12094.971927386183, 2238.6530087836413, 2756.54517004694, 2791.0649932272713, 2797.2866918935542, 2830.872610732522, 97278.0, 112480.0, 114913.0, 119450.0, 121100.39449802783, 9136.58885350161, 10918.399488028075, 10975.28660637056, 11050.834237486444, 11542.596009139808, 17686.027268500355, 20589.38231853047, 20709.275175368788, 20804.271850485162, 20904.79095209059, 6588.0, 7759.913612094311, 7776.027996500437, 7934.523928564305, 8073.0, 4092.242935057024, 4226.408302837605, 4239.59723826236, 4268.103698223345, 4269.423627810206, 3878.70909681775, 3924.7645141291614, 3932.528096628366, 3961.8217180226957, 3973.2848087344555, 683.4361651637419, 695.2526034512919, 732.3006528765017, 742.3504433620582, 802.8318796151585, 517.6350672775684, 541.2909089093222, 555.3141869790886, 574.3854076138492, 590.1943846649293, 9992.350497217803, 10201.183905287558, 10248.846267305997, 10378.014088661565, 10482.947585262025, 533.6184627990928, 565.7143142712986, 570.5920267009109, 584.570340799512, 587.6797145555682, 4430.291153415503, 4674.182018146811, 4681.789319480631, 4684.461286952044, 4705.95294047062, 107651.0, 108317.0, 109026.0, 109597.0, 110126.0, 96177.0, 96657.84612312175, 98048.0, 98061.03877922545, 99502.00497995073, 66355.0, 79848.0, 80598.98502537483, 82400.17599824045, 84169.57915210385, 7787.9096926431475, 8842.734717958472, 9622.663206787729, 9918.40489570628, 10065.446400447752, 36416.27167456689, 38487.0, 38728.0, 39659.80170099131, 39865.601343986185, 69616.0, 72438.0, 72458.63770681113, 74361.0, 76038.6198069006, 12901.677458063248, 12942.547010854574, 13078.476860925457, 13230.338483075615, 13549.72900541964, 14290.0, 14407.279636018367, 14416.783748243575, 14626.634334141307, 14751.409943602135, 13394.89153358693, 13422.865771342162, 13476.865231347561, 13508.797223865655, 13750.106243094167, 13361.465541378624, 13536.458541658223, 13597.796033059314, 13619.046666733233, 13700.794488082487, 11821.091047063877, 12744.74510509803, 12768.872311276768, 12776.808347874603, 13345.199288042748, 9391.906080939376, 9489.383190092622, 9556.378835375679, 9901.455419951972, 10085.495725213857, 8466.464776845358, 9773.0, 10660.200484963503, 10944.0, 11279.492422840827], "value": 7339.074784896107, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1689.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 185.0, 153.0, 121.0, 123.0, 125.0, 126.0, 167.0, 179.0, 167.0], "value": 146.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 105.0, 148.0, 200.0, 154.0, 118.0, 111.0, 116.0, 147.0, 157.0], "value": 145.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [227.0, 223.0, 296.0, 237.0, 217.0, 209.0, 242.0, 211.0, 212.0, 213.0], "value": 228.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 60.0, 61.0, 87.0, 61.0, 62.0, 58.0, 65.0, 66.0, 86.0], "value": 69.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 71.0, 88.0, 73.0, 72.0, 75.0, 85.0, 73.0, 73.0, 86.0], "value": 79.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 88.0, 112.0, 107.0, 119.0, 141.0, 99.0, 142.0, 139.0, 96.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 105.0, 124.0, 100.0, 99.0, 99.0, 99.0, 100.0, 158.0, 100.0], "value": 113.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 147.0, 112.0, 101.0, 103.0, 106.0, 101.0, 101.0, 105.0, 158.0], "value": 119.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 53.0, 53.0, 53.0, 53.0, 55.0, 53.0, 52.0, 53.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 75.0, 69.0, 69.0, 87.0, 87.0, 71.0, 61.0, 60.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 90.0, 90.0, 90.0, 100.0, 86.0, 89.0, 91.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 150.0, 175.0, 153.0, 151.0, 183.0, 172.0, 157.0, 209.0, 157.0], "value": 166.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [240.0, 199.0, 196.0, 197.0, 210.0, 194.0, 255.0, 265.0, 196.0, 204.0], "value": 215.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 87.0, 98.0, 103.0, 72.0, 72.0, 73.0, 71.0, 69.0, 72.0], "value": 79.3, "unit": "score"}]}]} 05:23:09 INFO - Return code: 0 05:23:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:23:09 ERROR - # TBPL SUCCESS # 05:23:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:23:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:23:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:23:09 INFO - 2017-04-04 05:23:09.885 screenresolution[1657:10550] starting screenresolution argv=screenresolution get 05:23:09 INFO - 2017-04-04 05:23:09.888 screenresolution[1657:10550] Display 0: 1600x1200x32@60 05:23:09 INFO - 2017-04-04 05:23:09.894 screenresolution[1658:10553] starting screenresolution argv=screenresolution list 05:23:09 INFO - Available Modes on Display 0 05:23:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:23:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:23:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:23:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:23:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:23:10 INFO - Intel Iris: 05:23:10 INFO - Chipset Model: Intel Iris 05:23:10 INFO - Type: GPU 05:23:10 INFO - Bus: Built-In 05:23:10 INFO - VRAM (Dynamic, Max): 1536 MB 05:23:10 INFO - Vendor: Intel (0x8086) 05:23:10 INFO - Device ID: 0x0a2e 05:23:10 INFO - Revision ID: 0x0009 05:23:10 INFO - Displays: 05:23:10 INFO - Display: 05:23:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:23:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:23:10 INFO - Display Serial Number: 1600x1200 60 05:23:10 INFO - Main Display: Yes 05:23:10 INFO - Mirror: Off 05:23:10 INFO - Online: Yes 05:23:10 INFO - Rotation: Supported 05:23:10 INFO - Return code: 0 05:23:10 INFO - Running post-action listener: _resource_record_post_action 05:23:10 INFO - [mozharness: 2017-04-04 12:23:10.130984Z] Finished run-tests step (success) 05:23:10 INFO - Running post-run listener: _resource_record_post_run 05:23:10 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 39467008; Write bytes: 518137344; Read time: 393; Write time: 1656 05:23:10 INFO - TinderboxPrint: CPU usage
54.4% 05:23:10 INFO - TinderboxPrint: I/O read bytes / time
39,467,008 / 393 05:23:10 INFO - TinderboxPrint: I/O write bytes / time
518,137,344 / 1,656 05:23:10 INFO - TinderboxPrint: CPU idle
2,189.8 (45.6%) 05:23:10 INFO - TinderboxPrint: CPU system
971.5 (20.2%) 05:23:10 INFO - TinderboxPrint: CPU user
1,644.5 (34.2%) 05:23:10 INFO - TinderboxPrint: Swap in / out
251,301,888 / 0 05:23:10 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 207305216; Write bytes: 205518336; Read time: 15780; Write time: 432 05:23:10 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 37509120; Write bytes: 301801472; Read time: 370; Write time: 1190 05:23:10 INFO - Running post-run listener: _upload_blobber_files 05:23:10 INFO - Blob upload gear active. 05:23:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:23:10 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:23:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:23:11 INFO - (blobuploader) - INFO - Open directory for files ... 05:23:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:23:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:23:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:23:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:23:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:23:12 INFO - (blobuploader) - INFO - Done attempting. 05:23:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:23:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:23:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:23:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:23:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:23:13 INFO - (blobuploader) - INFO - Done attempting. 05:23:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:23:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:23:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:23:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:23:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:23:14 INFO - (blobuploader) - INFO - Done attempting. 05:23:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:23:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:23:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:23:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:23:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:23:16 INFO - (blobuploader) - INFO - Done attempting. 05:23:16 INFO - (blobuploader) - INFO - Iteration through files over. 05:23:16 INFO - Return code: 0 05:23:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:23:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:23:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/873ca2a3efdba213de4e055b715eefc118d02576c841dae873bf74a1937ade9babbfd3e514cbfa5445a771e131089bec155dfd6ac6ca9ea0ad9e838745d66893", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8635f6c6932173106428dba79ac8d44ef84774b18e1c3ff7f8d86d7c32e7b2db03a3085a33445eefaf2c8e21f57ba2597ad3dbe230483f6d21e619243902b58", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d22d3ec4c962e966af9cfec11a299f3b11f292e45112700d0819a03dfce6404108af265b1a66079c5a5124839eb01e6e26bef534d9f22de272f8c014364d1297", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cea5f0ebfd2c1fb5527f4c4b70d1c4e43e173c9dd45c432914ca77717ced6fa79e4d9b99495293e9c33b1391d1a98cd5c09b9228d9caa9dd7008c3345837f477"} 05:23:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:23:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:23:16 INFO - Contents: 05:23:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/873ca2a3efdba213de4e055b715eefc118d02576c841dae873bf74a1937ade9babbfd3e514cbfa5445a771e131089bec155dfd6ac6ca9ea0ad9e838745d66893", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8635f6c6932173106428dba79ac8d44ef84774b18e1c3ff7f8d86d7c32e7b2db03a3085a33445eefaf2c8e21f57ba2597ad3dbe230483f6d21e619243902b58", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d22d3ec4c962e966af9cfec11a299f3b11f292e45112700d0819a03dfce6404108af265b1a66079c5a5124839eb01e6e26bef534d9f22de272f8c014364d1297", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cea5f0ebfd2c1fb5527f4c4b70d1c4e43e173c9dd45c432914ca77717ced6fa79e4d9b99495293e9c33b1391d1a98cd5c09b9228d9caa9dd7008c3345837f477"} 05:23:16 INFO - Running post-run listener: copy_logs_to_upload_dir 05:23:16 INFO - Copying logs to upload dir... 05:23:16 INFO - mkdir: /builds/slave/test/build/upload/logs 05:23:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.345109 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-04 05:23:16.379429) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-04 05:23:16.380084) ========= 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.nqOXIQRE9f/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.CXilRRjgOi/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/873ca2a3efdba213de4e055b715eefc118d02576c841dae873bf74a1937ade9babbfd3e514cbfa5445a771e131089bec155dfd6ac6ca9ea0ad9e838745d66893", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8635f6c6932173106428dba79ac8d44ef84774b18e1c3ff7f8d86d7c32e7b2db03a3085a33445eefaf2c8e21f57ba2597ad3dbe230483f6d21e619243902b58", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d22d3ec4c962e966af9cfec11a299f3b11f292e45112700d0819a03dfce6404108af265b1a66079c5a5124839eb01e6e26bef534d9f22de272f8c014364d1297", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cea5f0ebfd2c1fb5527f4c4b70d1c4e43e173c9dd45c432914ca77717ced6fa79e4d9b99495293e9c33b1391d1a98cd5c09b9228d9caa9dd7008c3345837f477"} build_url:https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011002 build_url: 'https://queue.taskcluster.net/v1/task/NYlB-lR2Tt-TeIcCxhuACQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/873ca2a3efdba213de4e055b715eefc118d02576c841dae873bf74a1937ade9babbfd3e514cbfa5445a771e131089bec155dfd6ac6ca9ea0ad9e838745d66893", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8635f6c6932173106428dba79ac8d44ef84774b18e1c3ff7f8d86d7c32e7b2db03a3085a33445eefaf2c8e21f57ba2597ad3dbe230483f6d21e619243902b58", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d22d3ec4c962e966af9cfec11a299f3b11f292e45112700d0819a03dfce6404108af265b1a66079c5a5124839eb01e6e26bef534d9f22de272f8c014364d1297", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cea5f0ebfd2c1fb5527f4c4b70d1c4e43e173c9dd45c432914ca77717ced6fa79e4d9b99495293e9c33b1391d1a98cd5c09b9228d9caa9dd7008c3345837f477"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-04 05:23:16.439801) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 05:23:16.440196) ========= 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.nqOXIQRE9f/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.CXilRRjgOi/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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.005202 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 05:23:16.489092) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-04 05:23:16.489406) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-04 05:23:16.489714) ========= ========= Total master_lag: 0.23 =========