builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0297 starttime: 1490100992.13 results: success (0) buildid: 20170321040528 builduid: 4a73f0e3d4ac45878248197d1192a579 revision: bd4f3810b402147f8656390555b29502ce5e2644 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 05:56:32.135018) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 05:56:32.135446) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 05:56:32.135718) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 05:56:32.161591) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:56:32.161900) ========= 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.VWj9piDNyG/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.RJZbIpgV9Z/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-21 05:56:32-- 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% 23.6M=0s 2017-03-21 05:56:32 (23.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137358 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:56:32.324202) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:56:32.324514) ========= 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.VWj9piDNyG/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.RJZbIpgV9Z/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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.025581 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:56:32.373445) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 05:56:32.373912) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bd4f3810b402147f8656390555b29502ce5e2644 --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 bd4f3810b402147f8656390555b29502ce5e2644 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VWj9piDNyG/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.RJZbIpgV9Z/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-21 05:56:32,528 truncating revision to first 12 chars 2017-03-21 05:56:32,528 Setting DEBUG logging. 2017-03-21 05:56:32,528 attempt 1/10 2017-03-21 05:56:32,529 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bd4f3810b402?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-21 05:56:33,855 unpacking tar archive at: mozilla-central-bd4f3810b402/testing/mozharness/ program finished with exit code 0 elapsedTime=1.600077 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 05:56:33.997666) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 05:56:33.998054) ========= script_repo_revision: bd4f3810b402147f8656390555b29502ce5e2644 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 05:56:33.998492) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 05:56:33.998809) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 05:56:34.018733) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-21 05:56:34.019061) ========= /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.VWj9piDNyG/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.RJZbIpgV9Z/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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:56:34 INFO - MultiFileLogger online at 20170321 05:56:34 in /builds/slave/test 05:56:34 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:56:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:56:34 INFO - {'append_to_log': False, 05:56:34 INFO - 'base_work_dir': '/builds/slave/test', 05:56:34 INFO - 'blob_upload_branch': 'Firefox', 05:56:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:56:34 INFO - 'branch': 'Firefox', 05:56:34 INFO - 'buildbot_json_path': 'buildprops.json', 05:56:34 INFO - 'config_files': ('talos/mac_config.py',), 05:56:34 INFO - 'default_actions': ('clobber', 05:56:34 INFO - 'read-buildbot-config', 05:56:34 INFO - 'download-and-extract', 05:56:34 INFO - 'populate-webroot', 05:56:34 INFO - 'create-virtualenv', 05:56:34 INFO - 'install', 05:56:34 INFO - 'run-tests'), 05:56:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:56:34 INFO - 'download_minidump_stackwalk': True, 05:56:34 INFO - 'download_symbols': 'ondemand', 05:56:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:56:34 INFO - 'tooltool.py': '/tools/tooltool.py', 05:56:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:56:34 INFO - '/tools/misc-python/virtualenv.py')}, 05:56:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:56:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:56:34 INFO - 'gecko_profile': False, 05:56:34 INFO - 'gecko_profile_interval': 0, 05:56:34 INFO - 'installer_path': 'installer.exe', 05:56:34 INFO - 'log_level': 'info', 05:56:34 INFO - 'log_name': 'talos', 05:56:34 INFO - 'log_to_console': True, 05:56:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:56:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:56:34 INFO - 'opt_config_files': (), 05:56:34 INFO - 'pip_index': False, 05:56:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:56:34 INFO - 'cmd': ('bash', 05:56:34 INFO - '-c', 05:56:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:56:34 INFO - 'enabled': True, 05:56:34 INFO - 'halt_on_failure': False, 05:56:34 INFO - 'name': 'check_screen_resolution'},), 05:56:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:56:34 INFO - 'cmd': ('bash', 05:56:34 INFO - '-c', 05:56:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:56:34 INFO - 'enabled': True, 05:56:34 INFO - 'halt_on_failure': False, 05:56:34 INFO - 'name': 'check_screen_resolution'},), 05:56:34 INFO - 'run_cmd_checks_enabled': True, 05:56:34 INFO - 'suite': 'dromaeojs-e10s', 05:56:34 INFO - 'system_bits': '32', 05:56:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:56:34 INFO - 'title': 't-yosemite-r7-0297', 05:56:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:56:34 INFO - 'use_talos_json': True, 05:56:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:56:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:56:34 INFO - 'work_dir': 'build'} 05:56:34 INFO - [mozharness: 2017-03-21 12:56:34.281849Z] Running clobber step. 05:56:34 INFO - Running pre-action listener: _resource_record_pre_action 05:56:34 INFO - Running main action method: clobber 05:56:34 INFO - rmtree: /builds/slave/test/build 05:56:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:56:36 INFO - Running post-action listener: _resource_record_post_action 05:56:36 INFO - [mozharness: 2017-03-21 12:56:36.645228Z] Finished clobber step (success) 05:56:36 INFO - [mozharness: 2017-03-21 12:56:36.645459Z] Running read-buildbot-config step. 05:56:36 INFO - Running pre-action listener: _resource_record_pre_action 05:56:36 INFO - Running main action method: read_buildbot_config 05:56:36 INFO - Using buildbot properties: 05:56:36 INFO - { 05:56:36 INFO - "project": "", 05:56:36 INFO - "product": "firefox", 05:56:36 INFO - "script_repo_revision": "production", 05:56:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:56:36 INFO - "repository": "", 05:56:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:56:36 INFO - "buildid": "20170321040528", 05:56:36 INFO - "pgo_build": "False", 05:56:36 INFO - "basedir": "/builds/slave/test", 05:56:36 INFO - "buildnumber": 14, 05:56:36 INFO - "slavename": "t-yosemite-r7-0297", 05:56:36 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:56:36 INFO - "platform": "yosemite_r7", 05:56:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:56:36 INFO - "branch": "mozilla-central", 05:56:36 INFO - "repo_path": "mozilla-central", 05:56:36 INFO - "revision": "bd4f3810b402147f8656390555b29502ce5e2644", 05:56:36 INFO - "stage_platform": "macosx64", 05:56:36 INFO - "builduid": "4a73f0e3d4ac45878248197d1192a579", 05:56:36 INFO - "slavebuilddir": "test" 05:56:36 INFO - } 05:56:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 05:56:36 INFO - Running post-action listener: _resource_record_post_action 05:56:36 INFO - [mozharness: 2017-03-21 12:56:36.651195Z] Finished read-buildbot-config step (success) 05:56:36 INFO - [mozharness: 2017-03-21 12:56:36.651418Z] Running download-and-extract step. 05:56:36 INFO - Running pre-action listener: _resource_record_pre_action 05:56:36 INFO - Running main action method: download_and_extract 05:56:36 INFO - mkdir: /builds/slave/test/build/tests 05:56:36 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:56:36 INFO - trying https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:56:36 INFO - Downloading https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/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:56:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/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:56:38 INFO - Downloaded 1325 bytes. 05:56:38 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:56:38 INFO - Using the following test package requirements: 05:56:38 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:56:38 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:56:38 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:56:38 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:56:38 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:56:38 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:56:38 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:56:38 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:56:38 INFO - u'jsshell-mac.zip'], 05:56:38 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:56:38 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:56:38 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:56:38 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:56:38 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:56:38 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:56:38 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:56:38 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:56:38 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:56:38 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:56:38 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:56:38 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:56:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:56:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:56:38 INFO - Fetch https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:56:39 INFO - Expected file size: 52473718 05:56:39 INFO - Obtained file size: 52473718 05:56:55 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:56:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:56:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:56:55 INFO - Fetch https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:56:57 INFO - Expected file size: 52473718 05:56:57 INFO - Obtained file size: 52473718 05:57:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:57:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:57:12 INFO - Fetch https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:57:13 INFO - Expected file size: 14404171 05:57:13 INFO - Obtained file size: 14404171 05:57:14 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:57:14 INFO - trying https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:57:14 INFO - Downloading https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:57:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/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:57:16 INFO - Downloaded 75232653 bytes. 05:57:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:57:16 INFO - mkdir: /builds/slave/test/properties 05:57:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:57:16 INFO - Writing to file /builds/slave/test/properties/build_url 05:57:16 INFO - Contents: 05:57:16 INFO - build_url:https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:57:17 INFO - Running post-action listener: _resource_record_post_action 05:57:17 INFO - Running post-action listener: set_extra_try_arguments 05:57:17 INFO - [mozharness: 2017-03-21 12:57:17.091783Z] Finished download-and-extract step (success) 05:57:17 INFO - [mozharness: 2017-03-21 12:57:17.091957Z] Running populate-webroot step. 05:57:17 INFO - Running pre-action listener: _resource_record_pre_action 05:57:17 INFO - Running main action method: populate_webroot 05:57:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:57:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:57:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:57:17 INFO - '--tppagecycles', 05:57:17 INFO - '7'], 05:57:17 INFO - 'tests': ['tsvgm']}}, 05:57:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:57:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:57:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:57:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:57:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:57:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:57:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:57:17 INFO - 'talos_options': ['--disable-e10s'], 05:57:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:57:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:57:17 INFO - 'talos_options': ['--disable-e10s'], 05:57:17 INFO - 'tests': ['damp', 'tps']}, 05:57:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:57:17 INFO - 'tests': ['damp', 'tps']}, 05:57:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:57:17 INFO - 'tests': ['dromaeo_dom']}, 05:57:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:57:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:57:17 INFO - 'tests': ['basic_compositor_video']}, 05:57:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:57:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:57:17 INFO - 'tests': ['a11yr', 05:57:17 INFO - 'ts_paint', 05:57:17 INFO - 'tpaint', 05:57:17 INFO - 'sessionrestore', 05:57:17 INFO - 'sessionrestore_no_auto_restore', 05:57:17 INFO - 'tabpaint']}, 05:57:17 INFO - 'other-e10s': {'tests': ['a11yr', 05:57:17 INFO - 'ts_paint', 05:57:17 INFO - 'tpaint', 05:57:17 INFO - 'sessionrestore', 05:57:17 INFO - 'sessionrestore_no_auto_restore', 05:57:17 INFO - 'tabpaint']}, 05:57:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:57:17 INFO - 'ts_paint', 05:57:17 INFO - 'tpaint', 05:57:17 INFO - 'sessionrestore', 05:57:17 INFO - 'sessionrestore_no_auto_restore', 05:57:17 INFO - 'tabpaint']}, 05:57:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:57:17 INFO - 'ts_paint', 05:57:17 INFO - 'tpaint', 05:57:17 INFO - 'sessionrestore', 05:57:17 INFO - 'sessionrestore_no_auto_restore', 05:57:17 INFO - 'tabpaint']}, 05:57:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:57:17 INFO - 'tests': ['a11yr', 05:57:17 INFO - 'ts_paint', 05:57:17 INFO - 'tpaint', 05:57:17 INFO - 'sessionrestore', 05:57:17 INFO - 'sessionrestore_no_auto_restore', 05:57:17 INFO - 'tabpaint']}, 05:57:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:57:17 INFO - 'tests': ['a11yr', 05:57:17 INFO - 'ts_paint', 05:57:17 INFO - 'tpaint', 05:57:17 INFO - 'sessionrestore', 05:57:17 INFO - 'sessionrestore_no_auto_restore', 05:57:17 INFO - 'tabpaint']}, 05:57:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:57:17 INFO - 'tests': ['tsvgx', 05:57:17 INFO - 'tsvgr_opacity', 05:57:17 INFO - 'tart', 05:57:17 INFO - 'tscrollx', 05:57:17 INFO - 'cart', 05:57:17 INFO - 'tsvg_static']}, 05:57:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:57:17 INFO - 'tsvgr_opacity', 05:57:17 INFO - 'tart', 05:57:17 INFO - 'tscrollx', 05:57:17 INFO - 'cart', 05:57:17 INFO - 'tsvg_static']}, 05:57:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:57:17 INFO - 'talos_options': ['--disable-e10s'], 05:57:17 INFO - 'tests': ['tp5o']}, 05:57:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:57:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:57:17 INFO - 'talos_options': ['--disable-e10s', 05:57:17 INFO - '--xperf_path', 05:57:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:17 INFO - 'tests': ['tp5n']}, 05:57:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:57:17 INFO - 'talos_options': ['--xperf_path', 05:57:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:17 INFO - 'tests': ['tp5n']}}, 05:57:17 INFO - 'talos.zip': {'path': '', 05:57:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:57:17 INFO - Running post-action listener: _resource_record_post_action 05:57:17 INFO - [mozharness: 2017-03-21 12:57:17.110441Z] Finished populate-webroot step (success) 05:57:17 INFO - [mozharness: 2017-03-21 12:57:17.110572Z] Running create-virtualenv step. 05:57:17 INFO - Running pre-action listener: _resource_record_pre_action 05:57:17 INFO - Running main action method: create_virtualenv 05:57:17 INFO - Creating virtualenv /builds/slave/test/build/venv 05:57:17 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:57:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:57:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:57:17 INFO - Using real prefix '/tools/python27' 05:57:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:57:17 INFO - Installing distribute.............................................................................................................................................................................................done. 05:57:20 INFO - Installing pip.................done. 05:57:20 INFO - Return code: 0 05:57:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:57:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:57:21 INFO - Reading from file tmpfile_stdout 05:57:21 INFO - Output received: 05:57:21 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:57:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ab4a3d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0d30>, '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 0x100bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc5be8>, '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.VWj9piDNyG/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.RJZbIpgV9Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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:57:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:57:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:57:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWj9piDNyG/Render', 05:57:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:21 INFO - 'HOME': '/Users/cltbld', 05:57:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:21 INFO - 'LOGNAME': 'cltbld', 05:57:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:21 INFO - 'MOZ_NO_REMOTE': '1', 05:57:21 INFO - 'NO_EM_RESTART': '1', 05:57:21 INFO - 'PAGER': '/bin/cat', 05:57:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:21 INFO - 'PWD': '/builds/slave/test', 05:57:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:21 INFO - 'SHELL': '/bin/bash', 05:57:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJZbIpgV9Z/Listeners', 05:57:21 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:57:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:21 INFO - 'USER': 'cltbld', 05:57:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:21 INFO - 'XPC_FLAGS': '0x0', 05:57:21 INFO - 'XPC_SERVICE_NAME': '0', 05:57:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:21 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:57:21 INFO - Cleaning up... 05:57:21 INFO - Return code: 0 05:57:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ab4a3d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0d30>, '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 0x100bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc5be8>, '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.VWj9piDNyG/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.RJZbIpgV9Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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:57:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:57:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:57:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWj9piDNyG/Render', 05:57:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:21 INFO - 'HOME': '/Users/cltbld', 05:57:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:21 INFO - 'LOGNAME': 'cltbld', 05:57:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:21 INFO - 'MOZ_NO_REMOTE': '1', 05:57:21 INFO - 'NO_EM_RESTART': '1', 05:57:21 INFO - 'PAGER': '/bin/cat', 05:57:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:21 INFO - 'PWD': '/builds/slave/test', 05:57:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:21 INFO - 'SHELL': '/bin/bash', 05:57:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJZbIpgV9Z/Listeners', 05:57:21 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:57:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:21 INFO - 'USER': 'cltbld', 05:57:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:21 INFO - 'XPC_FLAGS': '0x0', 05:57:21 INFO - 'XPC_SERVICE_NAME': '0', 05:57:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:21 INFO - Downloading/unpacking psutil>=3.1.1 05:57:21 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:57:21 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:57:21 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:57:21 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:57:21 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:57:21 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:57:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:57:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:57:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:57:25 INFO - Installing collected packages: psutil 05:57:25 INFO - Running setup.py install for psutil 05:57:25 INFO - building 'psutil._psutil_osx' extension 05:57:25 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:57:25 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:57:25 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:57:25 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:57:26 INFO - building 'psutil._psutil_posix' extension 05:57:26 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:57:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:57:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:57:26 INFO - ^ 05:57:26 INFO - 1 warning generated. 05:57:26 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:57:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:57:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:57:26 INFO - Successfully installed psutil 05:57:26 INFO - Cleaning up... 05:57:26 INFO - Return code: 0 05:57:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ab4a3d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0d30>, '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 0x100bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc5be8>, '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.VWj9piDNyG/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.RJZbIpgV9Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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:57:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:57:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:57:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWj9piDNyG/Render', 05:57:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:26 INFO - 'HOME': '/Users/cltbld', 05:57:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:26 INFO - 'LOGNAME': 'cltbld', 05:57:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:26 INFO - 'MOZ_NO_REMOTE': '1', 05:57:26 INFO - 'NO_EM_RESTART': '1', 05:57:26 INFO - 'PAGER': '/bin/cat', 05:57:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:26 INFO - 'PWD': '/builds/slave/test', 05:57:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:26 INFO - 'SHELL': '/bin/bash', 05:57:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJZbIpgV9Z/Listeners', 05:57:26 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:57:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:26 INFO - 'USER': 'cltbld', 05:57:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:26 INFO - 'XPC_FLAGS': '0x0', 05:57:26 INFO - 'XPC_SERVICE_NAME': '0', 05:57:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:57:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:57:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:57:29 INFO - Installing collected packages: mozsystemmonitor 05:57:29 INFO - Running setup.py install for mozsystemmonitor 05:57:29 INFO - Successfully installed mozsystemmonitor 05:57:29 INFO - Cleaning up... 05:57:29 INFO - Return code: 0 05:57:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:57: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:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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:57:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:30 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 0x100ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ab4a3d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0d30>, '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 0x100bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc5be8>, '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.VWj9piDNyG/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.RJZbIpgV9Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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:57:30 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:57:30 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:57:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWj9piDNyG/Render', 05:57:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:30 INFO - 'HOME': '/Users/cltbld', 05:57:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:30 INFO - 'LOGNAME': 'cltbld', 05:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:30 INFO - 'MOZ_NO_REMOTE': '1', 05:57:30 INFO - 'NO_EM_RESTART': '1', 05:57:30 INFO - 'PAGER': '/bin/cat', 05:57:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:30 INFO - 'PWD': '/builds/slave/test', 05:57:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:30 INFO - 'SHELL': '/bin/bash', 05:57:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJZbIpgV9Z/Listeners', 05:57:30 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:57:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:30 INFO - 'USER': 'cltbld', 05:57:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:30 INFO - 'XPC_FLAGS': '0x0', 05:57:30 INFO - 'XPC_SERVICE_NAME': '0', 05:57:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:30 INFO - Downloading/unpacking jsonschema==2.5.1 05:57:30 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:57:30 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:57:30 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:57:30 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:57:30 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:57:30 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:57:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:57:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:57:33 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:57:33 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:57:33 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:57:33 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:57:33 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:57:33 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:57:33 INFO - Downloading functools32-3.2.3-2.tar.gz 05:57:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:57:34 INFO - warning: no files found matching '*.txt' 05:57:34 INFO - no previously-included directories found matching 'build' 05:57:34 INFO - no previously-included directories found matching 'dist' 05:57:34 INFO - no previously-included directories found matching '.git*' 05:57:34 INFO - Installing collected packages: jsonschema, functools32 05:57:34 INFO - Running setup.py install for functools32 05:57:34 INFO - warning: no files found matching '*.txt' 05:57:34 INFO - no previously-included directories found matching 'build' 05:57:34 INFO - no previously-included directories found matching 'dist' 05:57:34 INFO - no previously-included directories found matching '.git*' 05:57:34 INFO - Successfully installed jsonschema functools32 05:57:34 INFO - Cleaning up... 05:57:34 INFO - Return code: 0 05:57:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:57:34 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:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:34 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:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:34 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 0x100ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ab4a3d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0d30>, '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 0x100bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc5be8>, '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.VWj9piDNyG/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.RJZbIpgV9Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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:57:34 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:57:34 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:57:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWj9piDNyG/Render', 05:57:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:34 INFO - 'HOME': '/Users/cltbld', 05:57:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:34 INFO - 'LOGNAME': 'cltbld', 05:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:34 INFO - 'MOZ_NO_REMOTE': '1', 05:57:34 INFO - 'NO_EM_RESTART': '1', 05:57:34 INFO - 'PAGER': '/bin/cat', 05:57:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:34 INFO - 'PWD': '/builds/slave/test', 05:57:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:34 INFO - 'SHELL': '/bin/bash', 05:57:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJZbIpgV9Z/Listeners', 05:57:34 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:57:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:34 INFO - 'USER': 'cltbld', 05:57:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:34 INFO - 'XPC_FLAGS': '0x0', 05:57:34 INFO - 'XPC_SERVICE_NAME': '0', 05:57:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:57:34 INFO - Cleaning up... 05:57:34 INFO - Return code: 0 05:57:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:57:34 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:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:34 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:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:34 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 0x100ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ab4a3d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0d30>, '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 0x100bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc5be8>, '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.VWj9piDNyG/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.RJZbIpgV9Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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:57:34 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:57:34 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:57:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWj9piDNyG/Render', 05:57:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:34 INFO - 'HOME': '/Users/cltbld', 05:57:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:34 INFO - 'LOGNAME': 'cltbld', 05:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:34 INFO - 'MOZ_NO_REMOTE': '1', 05:57:34 INFO - 'NO_EM_RESTART': '1', 05:57:34 INFO - 'PAGER': '/bin/cat', 05:57:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:34 INFO - 'PWD': '/builds/slave/test', 05:57:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:34 INFO - 'SHELL': '/bin/bash', 05:57:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJZbIpgV9Z/Listeners', 05:57:34 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:57:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:34 INFO - 'USER': 'cltbld', 05:57:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:34 INFO - 'XPC_FLAGS': '0x0', 05:57:34 INFO - 'XPC_SERVICE_NAME': '0', 05:57:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:34 INFO - Downloading/unpacking blobuploader==1.2.4 05:57:34 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:57:34 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:57:34 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:57:34 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:57:34 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:57:34 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:57:37 INFO - Downloading blobuploader-1.2.4.tar.gz 05:57:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:57:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:57:37 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:57:37 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:57:37 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:57:37 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:57:37 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:57:37 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:57:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:57:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:38 INFO - Downloading docopt-0.6.1.tar.gz 05:57:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:57:38 INFO - Installing collected packages: blobuploader, requests, docopt 05:57:38 INFO - Running setup.py install for blobuploader 05:57:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:57:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:57:38 INFO - Running setup.py install for requests 05:57:39 INFO - Running setup.py install for docopt 05:57:39 INFO - Successfully installed blobuploader requests docopt 05:57:39 INFO - Cleaning up... 05:57:39 INFO - Return code: 0 05:57:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57: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:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:39 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 0x100ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ab4a3d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0d30>, '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 0x100bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc5be8>, '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.VWj9piDNyG/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.RJZbIpgV9Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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:57:39 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:57:39 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:57:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWj9piDNyG/Render', 05:57:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:39 INFO - 'HOME': '/Users/cltbld', 05:57:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:39 INFO - 'LOGNAME': 'cltbld', 05:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:39 INFO - 'MOZ_NO_REMOTE': '1', 05:57:39 INFO - 'NO_EM_RESTART': '1', 05:57:39 INFO - 'PAGER': '/bin/cat', 05:57:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:39 INFO - 'PWD': '/builds/slave/test', 05:57:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:39 INFO - 'SHELL': '/bin/bash', 05:57:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJZbIpgV9Z/Listeners', 05:57:39 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:57:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:39 INFO - 'USER': 'cltbld', 05:57:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:39 INFO - 'XPC_FLAGS': '0x0', 05:57:39 INFO - 'XPC_SERVICE_NAME': '0', 05:57:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:57:39 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-iGEEv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:57:39 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-pFBIcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:57:40 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-Qd4LhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:57:40 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-TAyGK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:57:40 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-8G1iZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:57:40 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-HLQviE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:57:40 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-TX4yt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:57:40 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-JkUjvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:57:40 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-B6nHcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:57:40 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-3LjChH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:57:40 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-T6_C4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:57:41 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-SkmD3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:57:41 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-C49jTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:57:41 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-SHW7KI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:41 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-qPg14G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:57:41 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-RYULNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:57:41 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-DYuDZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:57:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:57:41 INFO - Running setup.py install for manifestparser 05:57:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:57:41 INFO - Running setup.py install for mozcrash 05:57:41 INFO - Running setup.py install for mozdebug 05:57:42 INFO - Running setup.py install for mozdevice 05:57:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Running setup.py install for mozfile 05:57:42 INFO - Running setup.py install for mozhttpd 05:57:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Running setup.py install for mozinfo 05:57:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Running setup.py install for mozInstall 05:57:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Running setup.py install for mozleak 05:57:42 INFO - Running setup.py install for mozlog 05:57:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:57:43 INFO - Running setup.py install for moznetwork 05:57:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:57:43 INFO - Running setup.py install for mozprocess 05:57:43 INFO - Running setup.py install for mozprofile 05:57:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:57:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:57:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:57:43 INFO - Running setup.py install for mozrunner 05:57:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:57:43 INFO - Running setup.py install for mozscreenshot 05:57:43 INFO - Running setup.py install for moztest 05:57:44 INFO - Running setup.py install for mozversion 05:57:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:57:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:57:44 INFO - Cleaning up... 05:57:44 INFO - Return code: 0 05:57:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57: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:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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:57:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:44 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 0x100ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ab4a3d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0d30>, '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 0x100bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc5be8>, '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.VWj9piDNyG/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.RJZbIpgV9Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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:57:44 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:57:44 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:57:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWj9piDNyG/Render', 05:57:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:44 INFO - 'HOME': '/Users/cltbld', 05:57:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:44 INFO - 'LOGNAME': 'cltbld', 05:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:44 INFO - 'MOZ_NO_REMOTE': '1', 05:57:44 INFO - 'NO_EM_RESTART': '1', 05:57:44 INFO - 'PAGER': '/bin/cat', 05:57:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:44 INFO - 'PWD': '/builds/slave/test', 05:57:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:44 INFO - 'SHELL': '/bin/bash', 05:57:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJZbIpgV9Z/Listeners', 05:57:44 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:57:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:44 INFO - 'USER': 'cltbld', 05:57:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:44 INFO - 'XPC_FLAGS': '0x0', 05:57:44 INFO - 'XPC_SERVICE_NAME': '0', 05:57:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:57:44 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-RCRvf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:57:44 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:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:57:44 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-UrX35e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:57:44 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:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:57:44 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-GhbDPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:57:44 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:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:57:44 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-DzhK1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:57:44 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:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:57:44 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-OmdWVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:57:45 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:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:57:45 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-ABUbVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:57:45 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:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:57:45 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-IYo_iJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:57:45 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:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:57:45 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-6FPlv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:57:45 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:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:57:45 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-JTD298-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:57:45 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:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:57:45 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-pvBDAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:57:45 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:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:57:45 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-aVEwV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:57:45 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:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:57:45 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-4tZokT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:57:45 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:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:57:45 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-3tcnRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:57:45 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:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:57:45 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-WhOMJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:57:46 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:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:46 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-aBGWNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:46 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:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:57:46 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-qAnq1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:57:46 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:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:57:46 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-X7MMaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:57:46 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:57:46 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:57:46 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:57:46 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:57:46 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:57:46 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:57:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:57:46 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:57:46 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:57:46 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:57:46 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:57:46 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:57:46 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:57:48 INFO - Downloading blessings-1.6.tar.gz 05:57:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:57:49 INFO - Installing collected packages: blessings 05:57:49 INFO - Running setup.py install for blessings 05:57:49 INFO - Successfully installed blessings 05:57:49 INFO - Cleaning up... 05:57:49 INFO - Return code: 0 05:57:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:57:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:57:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:57:49 INFO - Reading from file tmpfile_stdout 05:57:49 INFO - Current package versions: 05:57:49 INFO - blessings == 1.6 05:57:49 INFO - blobuploader == 1.2.4 05:57:49 INFO - docopt == 0.6.1 05:57:49 INFO - functools32 == 3.2.3-2 05:57:49 INFO - jsonschema == 2.5.1 05:57:49 INFO - manifestparser == 1.1 05:57:49 INFO - mozInstall == 1.12 05:57:49 INFO - mozcrash == 1.0 05:57:49 INFO - mozdebug == 0.1 05:57:49 INFO - mozdevice == 0.49 05:57:49 INFO - mozfile == 1.2 05:57:49 INFO - mozhttpd == 0.7 05:57:49 INFO - mozinfo == 0.9 05:57:49 INFO - mozleak == 0.1 05:57:49 INFO - mozlog == 3.4 05:57:49 INFO - moznetwork == 0.27 05:57:49 INFO - mozprocess == 0.25 05:57:49 INFO - mozprofile == 0.28 05:57:49 INFO - mozrunner == 6.13 05:57:49 INFO - mozscreenshot == 0.1 05:57:49 INFO - mozsystemmonitor == 0.3 05:57:49 INFO - moztest == 0.8 05:57:49 INFO - mozversion == 1.4 05:57:49 INFO - psutil == 3.1.1 05:57:49 INFO - requests == 1.2.3 05:57:49 INFO - wsgiref == 0.1.2 05:57:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:49 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 0x100ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ab4a3d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0d30>, '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 0x100bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc5be8>, '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.VWj9piDNyG/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.RJZbIpgV9Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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:57:49 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:57:49 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:57:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWj9piDNyG/Render', 05:57:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:49 INFO - 'HOME': '/Users/cltbld', 05:57:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:49 INFO - 'LOGNAME': 'cltbld', 05:57:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:49 INFO - 'MOZ_NO_REMOTE': '1', 05:57:49 INFO - 'NO_EM_RESTART': '1', 05:57:49 INFO - 'PAGER': '/bin/cat', 05:57:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:49 INFO - 'PWD': '/builds/slave/test', 05:57:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:49 INFO - 'SHELL': '/bin/bash', 05:57:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJZbIpgV9Z/Listeners', 05:57:49 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:57:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:49 INFO - 'USER': 'cltbld', 05:57:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:49 INFO - 'XPC_FLAGS': '0x0', 05:57:49 INFO - 'XPC_SERVICE_NAME': '0', 05:57:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:49 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:57:49 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:57:49 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:57:49 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:57:49 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:57:49 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:57:49 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:57:49 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:57:49 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:57:49 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:57:49 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:57:49 INFO - Cleaning up... 05:57:49 INFO - Return code: 0 05:57:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:49 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 0x100ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ab4a3d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0d30>, '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 0x100bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc5be8>, '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.VWj9piDNyG/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.RJZbIpgV9Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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:57:49 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:57:49 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:57:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWj9piDNyG/Render', 05:57:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:49 INFO - 'HOME': '/Users/cltbld', 05:57:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:49 INFO - 'LOGNAME': 'cltbld', 05:57:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:49 INFO - 'MOZ_NO_REMOTE': '1', 05:57:49 INFO - 'NO_EM_RESTART': '1', 05:57:49 INFO - 'PAGER': '/bin/cat', 05:57:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:49 INFO - 'PWD': '/builds/slave/test', 05:57:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:49 INFO - 'SHELL': '/bin/bash', 05:57:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJZbIpgV9Z/Listeners', 05:57:49 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:57:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:49 INFO - 'USER': 'cltbld', 05:57:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:49 INFO - 'XPC_FLAGS': '0x0', 05:57:49 INFO - 'XPC_SERVICE_NAME': '0', 05:57:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:57:50 INFO - Cleaning up... 05:57:50 INFO - Return code: 0 05:57:50 INFO - Running post-action listener: _resource_record_post_action 05:57:50 INFO - Running post-action listener: _start_resource_monitoring 05:57:50 INFO - Starting resource monitoring. 05:57:50 INFO - [mozharness: 2017-03-21 12:57:50.115776Z] Finished create-virtualenv step (success) 05:57:50 INFO - [mozharness: 2017-03-21 12:57:50.116755Z] Running install step. 05:57:50 INFO - Running pre-action listener: _resource_record_pre_action 05:57:50 INFO - Running main action method: install 05:57:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:57:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:57:50 INFO - Reading from file tmpfile_stdout 05:57:50 INFO - Detecting whether we're running mozinstall >=1.0... 05:57:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:57:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:57:50 INFO - Reading from file tmpfile_stdout 05:57:50 INFO - Output received: 05:57:50 INFO - Usage: mozinstall [options] installer 05:57:50 INFO - Options: 05:57:50 INFO - -h, --help show this help message and exit 05:57:50 INFO - -d DEST, --destination=DEST 05:57:50 INFO - Directory to install application into. [default: 05:57:50 INFO - "/builds/slave/test"] 05:57:50 INFO - --app=APP Application being installed. [default: firefox] 05:57:50 INFO - mkdir: /builds/slave/test/build/application 05:57:50 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:57:50 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:58:10 INFO - Reading from file tmpfile_stdout 05:58:10 INFO - Output received: 05:58:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:58:10 INFO - Running post-action listener: _resource_record_post_action 05:58:10 INFO - [mozharness: 2017-03-21 12:58:10.671944Z] Finished install step (success) 05:58:10 INFO - [mozharness: 2017-03-21 12:58:10.672073Z] Running run-tests step. 05:58:10 INFO - Running pre-action listener: _resource_record_pre_action 05:58:10 INFO - Running main action method: run_tests 05:58:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:58:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:58:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:58:10 INFO - 2017-03-21 05:58:10.686 screenresolution[1631:7582] starting screenresolution argv=screenresolution get 05:58:10 INFO - 2017-03-21 05:58:10.689 screenresolution[1631:7582] Display 0: 1600x1200x32@60 05:58:10 INFO - 2017-03-21 05:58:10.694 screenresolution[1632:7585] starting screenresolution argv=screenresolution list 05:58:10 INFO - Available Modes on Display 0 05:58:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:58:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:58:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:58:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:58:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:58:10 INFO - Intel Iris: 05:58:10 INFO - Chipset Model: Intel Iris 05:58:10 INFO - Type: GPU 05:58:10 INFO - Bus: Built-In 05:58:10 INFO - VRAM (Dynamic, Max): 1536 MB 05:58:10 INFO - Vendor: Intel (0x8086) 05:58:10 INFO - Device ID: 0x0a2e 05:58:10 INFO - Revision ID: 0x0009 05:58:10 INFO - Displays: 05:58:10 INFO - Display: 05:58:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:58:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:58:10 INFO - Display Serial Number: 1600x1200 60 05:58:10 INFO - Main Display: Yes 05:58:10 INFO - Mirror: Off 05:58:10 INFO - Online: Yes 05:58:10 INFO - Rotation: Supported 05:58:10 INFO - Return code: 0 05:58:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:58:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:58:10 INFO - Python 2.7.3 05:58:10 INFO - Return code: 0 05:58:10 INFO - grabbing minidump binary from tooltool 05:58:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:10 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 0x100cd0d30>, '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 0x100bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:58:10 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:58:10 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:58:10 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:58:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:58:11 INFO - Return code: 0 05:58:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:58:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:58:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:58:11 INFO - ENV: RUST_BACKTRACE is now 1 05:58:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:58:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:58:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0297', '--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:58:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0297 --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:58:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWj9piDNyG/Render', 05:58:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:58:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:58:11 INFO - 'HOME': '/Users/cltbld', 05:58:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:58:11 INFO - 'LOGNAME': 'cltbld', 05:58:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:58:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:58:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:11 INFO - 'MOZ_NO_REMOTE': '1', 05:58:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:58:11 INFO - 'NO_EM_RESTART': '1', 05:58:11 INFO - 'PAGER': '/bin/cat', 05:58:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:58:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:11 INFO - 'PWD': '/builds/slave/test', 05:58:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:58:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:58:11 INFO - 'RUST_BACKTRACE': '1', 05:58:11 INFO - 'SHELL': '/bin/bash', 05:58:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJZbIpgV9Z/Listeners', 05:58:11 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:58:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:58:11 INFO - 'USER': 'cltbld', 05:58:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:58:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:58:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:11 INFO - 'XPC_FLAGS': '0x0', 05:58:11 INFO - 'XPC_SERVICE_NAME': '0', 05:58:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:58:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0297', '--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:58:11 INFO - mozversion application_buildid: 20170321040528 05:58:11 INFO - mozversion application_changeset: bd4f3810b402147f8656390555b29502ce5e2644 05:58:11 INFO - mozversion application_display_name: Nightly 05:58:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:58:11 INFO - mozversion application_name: Firefox 05:58:11 INFO - mozversion application_remotingname: firefox 05:58:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:58:11 INFO - mozversion application_vendor: Mozilla 05:58:11 INFO - mozversion application_version: 55.0a1 05:58:11 INFO - mozversion platform_buildid: 20170321040528 05:58:11 INFO - mozversion platform_changeset: bd4f3810b402147f8656390555b29502ce5e2644 05:58:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:58:11 INFO - mozversion platform_version: 55.0a1 05:58:11 INFO - using testdate: 1490101091 05:58:11 INFO - actual date: 1490101091 05:58:11 INFO - starting webserver on 'localhost:49242' 05:58:11 INFO - SUITE-START | Running 2 tests 05:58:11 INFO - TEST-START | dromaeo_css 05:58:11 INFO - operating with platform_type : mac_ 05:58:11 INFO - Initialising browser for dromaeo_css test... 05:58:11 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmpEMHAEI/profile http://localhost:49242/getInfo.html) 05:58:14 INFO - PID 1642 | 2017-03-21 05:58:14.365 plugin-container[1644:7768] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 05:58:14 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:58:14 INFO - PID 1642 | 2017-03-21 05:58:14.368 plugin-container[1644:7768] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 05:58:14 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:58:14 INFO - PID 1642 | 2017-03-21 05:58:14.369 plugin-container[1644:7768] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:58:14 INFO - PID 1642 | __metrics Screen width/height:1600/1200 05:58:14 INFO - PID 1642 | colorDepth:24 05:58:14 INFO - PID 1642 | Browser inner width/height: 1024/689 05:58:14 INFO - PID 1642 | __metrics 05:58:14 ERROR - PID 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 05:58:15 INFO - TEST-INFO | 1642: exit 0 05:58:15 INFO - Browser initialized. 05:58:15 INFO - Running cycle 1/1 for dromaeo_css test... 05:58:15 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmpEMHAEI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:58:17 INFO - PID 1645 | 2017-03-21 05:58:17.789 plugin-container[1646:7896] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 05:58:17 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:58:17 INFO - PID 1645 | 2017-03-21 05:58:17.790 plugin-container[1646:7896] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 05:58:17 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:58:17 INFO - PID 1645 | 2017-03-21 05:58:17.790 plugin-container[1646:7896] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:58:27 INFO - PID 1645 | RSS: Main: 227876864 05:58:27 INFO - PID 1645 | 06:01:31 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 06:01:32 INFO - PID 1645 | RSS: Main: 250785792 06:01:32 INFO - PID 1645 | 06:03:15 INFO - PID 1645 | ************************* 06:03:15 INFO - PID 1645 | A coding exception was thrown and uncaught in a Task. 06:03:15 INFO - PID 1645 | 06:03:15 ERROR - PID 1645 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:03:15 INFO - PID 1645 | Full stack: 06:03:15 INFO - PID 1645 | ************************* 06:04:35 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 06:04:35 INFO - PID 1645 | RSS: Main: 258584576 06:04:35 INFO - PID 1645 | 06:07:37 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 06:07:38 INFO - PID 1645 | RSS: Main: 269746176 06:07:38 INFO - PID 1645 | 06:10:40 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 06:10:40 INFO - PID 1645 | RSS: Main: 264757248 06:10:40 INFO - PID 1645 | 06:13:43 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 06:13:44 INFO - PID 1645 | RSS: Main: 274513920 06:13:44 INFO - PID 1645 | 06:16:47 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 06:16:47 INFO - PID 1645 | RSS: Main: 268144640 06:16:47 INFO - PID 1645 | 06:16:47 INFO - PID 1645 | __start_tp_report 06:16:47 INFO - PID 1645 | _x_x_mozilla_page_load 06:16:47 INFO - PID 1645 | _x_x_mozilla_page_load_details 06:16:47 INFO - PID 1645 | |i|pagename|runs| 06:16:47 INFO - PID 1645 | |0;dojo.html;2923.722246386595;3058.3577448735764;3527.788332700037;3550.1302180965668;3612.5123108380362;6135.478484328826;6229.345918678536;6234.906476402852;6257.468115210207;6284.937150628503;6134.2529056856065;6171.567990240674;6197.473214776738;6200.472959798422;6244.687765611724;5596.468335508121;5657.066584013633;5731.799387021455;5786.710664466781;5820.330661973863;4705.882352941187;5614.073677843151;5662.801801936933;5761.625494342875;5830.067189249721;5532.170174473861;5656.123300888369;5707.74315155819;5759.424057594228;5770.798022069229;5455.836324910219;5497.587680923947;5531.032069387841;5575.8884822303735;5576.665400076008;5517.889642207174;5542.279503664538;5555.833325000256;5569.777208911639;5650.180723795068;5152.227165925104;5754.625949313281;6125.111858780452;6253.87492250157;6258.405451482102;6357.968210158966;6438.742450301982;6507.44686701631;6613.966930165318;6636.336366363325;5823.883522329572;6625.105610742585;6781;6794.082798822145;6806.115205023365;6197.721102550396;6329.335419780857;6331.746730130789;6355.8411039723915;6363.077353783676;6662.167229096363;6748.392644661906;6924.619145947021;6956.339147780953;6975.720971161097;6291.245050593958;6364.236291644939;6367.808965731036;6432.388923052303;6481.09264702942;6578.177727784027;6635.568688035262;6649.135612370457;6650.3682150195655;6650.800475985729;6590.9670451647435;6602.2407423146;6608.471322294301;6659.134312539339;6661.867482527982;6253;6409.9679501602195;6670.866582668319;6697.296783837627;6708;5244.31823862903;5316.893662126813;5360.088784906575;5384.784608615611;5414.18787181918;4675;4687.2500399936525;4690.592822153409;4700.388949436551;4731.5332247003535;6345.460635845912;6369.808905732835;6370;6370.649614271259;6383.489320854321;6447.80656580294;6608.570442921195;6665.100211471487;6704.463642908556;6748.426383757337;5232.450592687789;6027.8848413043725;6346.11154438379;6358.33237510064;6361.745530178833;4991;5887.322957859816;5897.7935772247765;5938.703064846741;5953.940460595425;5824.242848429677;6669.499787515859;6801.591904485746;6829.078074459884;6857.040014398088;6711.697973591103;6719.764808231884;6755.7973260802255;6758.42553382968;6804.795856124324;6877.759278425231;6949.339812717783;6952.409045231212;6960.547564408297;6962.895556566555;5888.587798854039;6335.208098987627;6358.459530939737;6593.934060659524;6617.07360969455;6586.670666466562;6647.169103862017;6710.362515561014;6726.495512836459;6751.864962700817;5094.898102038012;6298.842528936813;6520.967395162994;6532.41208291256;6538.411542961157;6201.193844800147;6348.396902294275;6373.2989371168205;6375.649339286384;6401.583897046677;5538.280023596906;6177.104319873503;6371.649559274284;6379.393957574023;6398.840028999312;6496.610203387811;6500.902486462613;6526.510511711545;6535.117759102461;6554.410103090745;6241.906371404523;6254.530910181846;6346.460550853045;6382.489400847828;6412.743490260524 06:16:47 INFO - PID 1645 | |1;ext.html;587.2571197435245;807.5881300536724;808.7371604228626;815.5310696349596;841.2765022081011;40202.59797402025;41345.79327103364;41367.58632413675;41368.75893723189;42172.578274217245;39815.8055258342;40133.59866401335;40168;40222.59777402225;40317.99205019876;2689.9462010759726;2746.533089374811;2826.7879909006806;2908.258394109499;2913.1260621813476;2856.657201135856;2881;2899.6375453068367;2901.9419611607755;2911.257629304535;2869.5695645653213;2876.6835648078695;2903.2451562593665;2907.8982235621756;2909.956350654742;2872.755815755663;2904.6078779364834;2909.2872246299794;2923.8976635817644;2924.6051783009234;2907.0552070576937;2917.212352664769;2923.0792300425296;2923.63454568179;2924.7806414518996;39407.21185576301;39701.40447893284;39717.602823971676;39752.20495590101;40060.79969600133;3061.8928337508305;3065.6934306569165;3102.9844850775603;3107.0989413070183;3127.077512133926;27995.580066299022;28721;29007.274818129503;29166.27084322909;29796.85101574478;2786.2755683522228;2817.0844475545528;2820.3090242890416;2821;2927.34134819666;342777;347502;348793.25603372074;348905;349437;51745.7412712934;51763.223551646755;51831.22253166205;52028;52111.73944130331;54024.91950160975;54361.91276174454;54427;54667.90664186694;54685;14363.928180359031;14624.707505850036;14681.706365872622;14688.265586720621;14709;11832.23090499132;11833.644990650295;11844.585439509576;11858.466369013415;11866.584669536525;13333.266670333225;13773.898088152924;13793;13794.310284485937;13845.861541384456;5120.743962801845;5179.8446046618665;6108.205933228741;6130.7241174146375;6180.072989051679;865.4807115730637;899.6446403670514;978.574320170728;1000.3547711725896;1002.8445590933417;25054.498910022063;25143.497130057298;28415.43169136606;29155.708442915722;29908.102756917327;921.7465197070725;1051.4690081508788;1053.1785207538132;1080.708208783624;1106.80630889594;12724.618261452171;12876.03429742773;12889.742205155844;12944.805827912589;12972.416241269166;482987;485863;488593;488959;489716;42021.579784202375;42487.57512424898;57952.4204757947;57978;58778;1458.1761304862719;1472.168224952898;1473.889458290654;1484.3394689363129;1490.135721281654;28028.85985570059;28213;28865.989690360737;28929.842806287514;29002.419951600426;38865.805670971466;38884.805575971935;38939.15912613131;39119.21761564696;39291.60708392879;389954;390182;391823;393216;394905;33375.83312083521;34497;34836.651633484355;34878;34981.82509087438;43657.56342436535;43766.78116609396;43849.56150438455;45237.547624524654;45279.09441811211;35994.92015239547;36545.634543654225;37366.439503408015;37491;37791.81104094572;29728;34742;35894.8205258972;37119;37139;35434.822825885705;36079.27841443209;36685.63314366822;36975.70585029511;37138.814305928296;30649.387012260075;30854.074377768702;32335;32446.513302300955;32467.67532324741;21690.783092168876;22978;24041.918113685275;24268.02927882865;25054.62418063694 06:16:47 INFO - PID 1645 | |2;jquery.html;376.6591234932386;384.52319124285896;431.5705872656707;432.23062947952644;452.20637780694875;19588;20627;21206;21326.827024513645;21380.35858924233;21243;21601.783982160214;22101;22118.33644990649;22285.220017299398;4089.836406543742;4276.037891474409;4307.719998200123;4311.3101903695415;4312.180685669713;3614.367485690007;4262.829486820539;4276.973526353684;4278.315469524877;4292.763897985626;4075.572064933184;4201.1807697499;4250.829966801324;4254.106637606106;4255.085156691311;4234.661227101825;4237.131388065439;4264.701470897038;4271.124419493996;4272.658187345005;3810.40938654509;3908.7263891527605;3922.5096862892137;3924.960750392488;3953.8220780064967;25814.09650662218;26957.730422695717;27091.593626095626;27166.1850144496;27608.309792255153;1941.3011315926256;1955.5013471564803;1965.665836807746;1979.8614097013215;2010.9095090720953;15722.685546289125;16223.945443546132;16289.429869954494;16408.83591164085;17561.20974556135;1699.1504247876062;1723.4312676816746;1724.4912750738563;1756.2974810075943;1781.741647461111;148336.2583187088;157617;159071.20464397603;159434.20282898512;159970.20014899966;11316.321020738782;12255.203411778206;12503.374561306971;12506.999440044774;12610.991120710321;15768.92115539415;15773.211339432983;15799.447019354267;15800.130992795506;15954.521364359089;8762.824743505094;9019.819603608023;9089.31830112731;9179.770505737277;9306.348555601178;7346.375558077516;8933.865992010125;8935.151160639858;9022.458652480873;9089.318301127441;6626.304238055031;7901.209879012053;7964.761057168178;8030.437869349206;8037.477563958324;2236.1279101150562;2241.7982381585734;2246.0791075658904;2247.2808701215454;2435.147698305614;833.0586437325783;868.5353335965228;911.6900253913701;956.7895063086249;986.9210463163073;18378.264869405342;20393.49016274605;20397.49006274855;20535.4866128345;20839.479013024797;819.72948926854;873.5195642396657;919.6183583812776;949.9952500237592;990.153418826907;9233.861492077624;9445.244380449547;10536.32635270351;10598.629047983284;10750.247482676294;678882;694781;698114;703479;719089;99534;116957;147142;148037;159177;1169.2984209474248;1205.572021932219;1218.65268398506;1220.6277085489012;1225.7977433723513;20121.39635810928;20952.533322667266;21974.780252197274;22040.008199630734;22062.33812985613;45863;47096.529034709216;53180.936381271385;57176.42823571711;61316;90527.2288580022;91307.63038554294;91945;91970;92512.07487925034;8306.210909963544;8550.401471896908;8592.945064680915;8926.955365223132;8955.194032537103;13823.930880345533;13841.307934603028;13937.372818223;14229.932755043206;14262.857371426153;11426;11645.825312620487;11768.82346764782;11951.342676152548;12144.210626309261;11644.6506604802;11664.825027624762;11710.473028713557;11933.403329833647;12034.63896083119;9927.652532161628;10644.308119972176;11843.881561184278;11990.460429280529;12170.77474469284;8543.401961862859;8639.352048596254;8655.394122411371;8750.737477875675;8847.36818478813;11547.249428787101;11755.706107347043;11822.527098915947;12027.518899244282;12049.33728644933 06:16:47 INFO - PID 1645 | |3;mootools.html;1175.794810319423;1389.6734267447148;1439.2012433099633;1441.3441883942808;1456.5120684570672;9356.71929842105;10325.173986081114;10529.684109476719;10568.471576421167;10582.312149710264;10452.947735261332;10616.363018218894;10630.946845265762;10636.255462117655;10647.935206479347;2381.7737314955093;2408.590539608262;2417.057347634424;2421.5278020785954;2422.527607116613;2021.5451523407262;2387.916422925198;2398;2400.7719266669724;2401.4836810085826;2332.11379675723;2365.1603680693365;2371.988140059297;2372.857628542284;2374.6319320505263;2310.676505289261;2327.674125622414;2341.285907798121;2342.590046741822;2350.9882450587725;2195.769444208367;2236.848023268018;2292.518571100071;2364.97635023651;2390.796782273509;9942.90057099427;10400.84398734012;10458.947705261426;10485.737856553571;10488.475576221159;4860.781264843091;5402.405735369106;5939.406059394069;6226.719797609118;6227.813165604994;3788.8484460621544;3799.9930018545106;3864.3623802072625;3899.200663863901;3927.9607203928167;1128.266626692648;1131.4173200801595;1154.1920655541155;1182.6924999500106;1185.3362117214388;13478.393472293672;13633.522826701117;14413.639659008608;14759.18824464654;14888;5007.37407824022;5032.823851165192;5160.948390516121;5186.559142472856;5187.610929180251;10956.452177391098;11001.834972475419;11017.338959662446;11036.668899933014;11043.503042363112;6127.754889804358;6128.9693551531955;6145.324014358417;6153.969230153821;6160.507159427235;5467.699276539749;5521.365043020024;5526.613137080446;5599.188117722907;5686.748915238641;4103.199876024206;4191.790410479463;4208.516020657664;4241.660667146621;4247.8088486018205;1126.8478755368085;1251.4226359393776;1252.7244006318595;1263.6588121207224;1268.1376663868473;746.5707218349362;746.9365103966223;767.4359345880773;772.8415674786655;804.6781287485053;6336.144620476269;6495.3179916109075;6671.799846004627;6688.996650502463;6731.562448440934;742.9591372474566;873.1355957602055;873.2010210657304;889.5374405309333;893.5532233883058;3515.648435156464;4097.09863829957;4105.733127346773;4187.183499217685;4241.321388577812;13324;13640.522581709592;13747.450101996008;13755.31223438824;13866;12748.61754147377;13255.403506842214;13724.93137534306;13777.793333100011;13781;8849.91150088491;9832.557534910946;10106.140978016803;10117.797644047077;10121.949390253001;5653.293338332708;6450.491435269139;6459.580127291711;6628.900566491603;6710.932890671031;11791.528338866348;11936.701582460508;12031.398430078287;12285.38573071325;12390.690232743893;12260.080493963167;12356.876431235933;12421.0063194946;12542.059345548938;12669.74660506803;8871.201591856763;8886.688965886164;9007.144321289466;9050.049744776703;9100.77248068777;9898.356606820533;9926.851097233402;9950.016628033456;10056.798864022825;10074.294799363974;8657.696980605648;8817.911820881965;8818.779530511789;8878.95560522219;8907.465552066897;8516.659333626585;8561.82876342482;8622.741317760478;8624.741257762276;8638.26574741154;7573.583452910143;7706.460547761827;7746.92253077462;7750;7752.496087754278;5117.820876269313;5642.322921249476;5709.600327977002;5765.913511297418;5930.377310382348;5122.334096567422;5348.625596208228;5561.6663000220115;5793.536517078539;6078.696065196811 06:16:47 INFO - PID 1645 | |4;prototype.html;320.34809163352577;366.46862050027465;367.59748075856925;387.29512287636504;388.1325238092864;5711.515490761516;5906.755432935479;6152.907706384397;6305.7792977245745;6352.555321127517;5014.9498505014935;5616.859578510529;5966.224390829197;6233.005438259759;6237.189165408502;4775.474697783241;4776.498467660897;4790.712557246559;4795.424549054118;4804.855854324358;3926.155876486555;4234.364845273203;4436.911261774762;4788.329633851264;4789.97015641637;4696.741679207642;4698.68436837686;4726.716397016173;4728.314394412808;4796.328514008042;4349.891252718676;4743.075100355432;4770.713757174546;4777.784999675023;4790.616750659956;3584.426491761306;3657.579378371468;3827.158025234444;3876.7673939563715;4179.080602267496;4852.8544143675745;5065.974670126626;5805.535557155417;5857.824265272048;5996.4902983246475;4500.864974050751;4557.723837325521;5130.127878260705;5255.185446255837;5294.205869119624;5632.295963004624;6249.5625306228585;6457.677116144174;6475.320091390383;6510.1536800216145;3672.2288319452946;3801.182745709658;4215.650991682663;4294.656427485794;4307.569243075667;5557.999560079184;6566.441852442548;6821.386075253251;6883.793486195422;6894.517383783137;3648;4111.232170166797;4316.719413238129;4369.978150109293;4377.671674624416;4200.474940632421;4418.977905110454;4421.756803375781;4443.733375997451;4459.420275364182;5256.605754568346;5257.237700533477;5313.1764576490705;5313.601479888946;5336.17289320156;6294.9685251574365;6343.809685709436;6392;6495.8700825983215;6573.474122070223;1469.8530146985215;1494.8340294570253;1497.7903093566908;1514.9091054536543;1539.538138558428;927.5547737086237;956.933014688979;996.9102780749768;1002.8567433126242;1004.5379125602304;6198.163247961557;6250.343713909974;6266.968665156736;6277.8744425111245;6338.207724034496;5743.798967036134;6858.6570671466225;6859.759908403182;6871.4502839772695;6872.381485666314;5017.724025178577;5914;5995.640261584319;6218.471429928416;6265.8746825063245;2909.2253725227592;3417.2311229973056;3544.9468257975636;3581.3374525712825;3583.9283214336087;5889.764409423575;6594.20869495654;6719.328067193339;6759.797206083826;6879.140107486564;6934.271901450376;7008.299170082951;7024;7069.363757261871;7081.575105493584;6755.290694477009;7037.964810175917;7098.68055937484;7110.466714996396;7160.606166660779;5736.741846616996;6751;6754.729810807709;6776.8652874344425;6780.1524809398825;6200.875982480416;6429.38920802523;6620.073189753342;6759.357861003196;6771.762988295386;5893.027650437802;6304.085907543289;6693.397594216543;7020.508564400443;7024.438044956493;4373.890652733707;4383.495897971774;4390.692651514491;4407.4711034674765;4415.757133357696;5910.52715782745;6153.8307721532765;6306.432421082125;6427.132337134427;6798.4221341184575;6366.808995730135;6601.33986601336;6614;6615.933840661724;6621.834454138685;5152.62503060496;6282.591631543935;6351.745930162741;6606.43845273138;6618.7352505899225;5857.26784151981;6108.75564977396;6300.779472718433;6413.422791948747;6419.935800642121;5791.362950075572;5930.377310382521;6101.84745381369;6598.5710928791395;6638.5684930479365;5325.467453254643;6179.28938172116;6402.455791257609;6462.773802916875;6477.093206950936 06:16:47 INFO - PID 1645 | |5;yui.html;589.0622524948519;692.1071817355613;696.9058578032491;707.731062196365;737.5095561451635;14222.217778022205;15012;15646.452374166907;15832.841671583281;15836.129012904314;14355;14653.92673036636;14708.117512949231;14726.26368681567;15070.246487675628;945.0360632155198;972.8248915195262;973.7322236385015;974.9707508774748;975.878015248094;945.4563625915092;969.8205831921082;986.6743974488437;999.2256001598746;1000.67978246961;1033.230243468617;1036.471399586209;1037.2531777120462;1039.7452624107104;1040.3185913226848;1015.8577799108131;1020.4489575629151;1028.7685270814045;1030.6032177611598;1035.1201478743083;757.4773406349601;768.673313841615;770.4491288728586;770.5415277909635;771.834055678032;16139.193040348053;16676.6664666706;16686.082265475394;16861.747073793904;17024;14351.856481435156;14591.05158164716;14636.780448293284;14687.926560367237;14780.482683106147;11677.88322116785;11937.582184623583;12099.62990895066;12103.113170799877;12244.510219591206;2997.760179185682;3063.6476805167467;3089.1350421881684;3169.255225022118;3169.6513383527795;88152;108301;110227.44886275598;110804;114298;9279.628814847465;10577.418241996764;10970.835437468444;11539.365334906493;11836.881631183578;23844.284671459885;23860.64209036866;24116.397090072536;24189.395265118514;24439.02243910259;7760.30157285847;7843.56860372685;7900.525968441912;7990.4007199460275;8151.714689985941;3975.284448799244;3976.622220889011;4062.471878655756;4095.201435720006;4095.836166553364;2991.5512673098774;3794.582595914447;4004.2191772604065;4017.196560687874;4029.4157347184496;780.6331024418514;781.1758594682665;789.1753117991713;790.8022994251437;806.8870358149863;526.2264471227367;541.7101850509958;546.7184400033985;559.8936202121583;564.9717514124277;9683.951580242054;9830.606775728891;10022.649207277711;10154.52757368422;10304;542.1975476295105;558.1683291895047;581.7440326256434;589.5519405252039;595.684287327717;4035.858744943913;4491.326301054868;4579;4606.792694328763;4718.834840780556;97854.04291914121;98770;102335;102714.48642756739;104859.475702621;87207.1279287199;89475;90188.54905725429;91482.0851791487;91953.54023229975;57985.8402831941;62257.68871155706;75469.24530754623;75626.62186689032;76324.61837690887;9226.35415520907;9233.215176710055;9258.814823703353;9301.906980930375;9363.953180234055;38181.23637527179;38791.64229252075;39220.41169382518;39263.41104883372;39297.01757455972;50182.24362147307;50384.24423633575;50490.990180195455;50572.735681608254;50627.74686126693;10161.491925403552;11580.131490138105;11629.47667354988;11634.476448559666;12179.269243845043;10429.426381549089;10570.471476425993;10574.312669676447;10613.257072004886;11401.315921044765;11267.154963377616;11607.593734219261;11611.767764644828;11661.825072624088;12307.323097229739;11455.312681239153;11524;11588.826167607323;12104.939475302566;12199.573014944433;11576.88423115758;11727.296362218294;11915.582954596548;12265.448054837732;12341.321227332224;9502.524873756147;9865.704028879145;10000.2999790014;10047.246456515937;10291.176705863363;8782.868256976022;9123.863142052742;9155.725328240163;9764.707058788248;9782.217422606316 06:16:47 INFO - PID 1645 | __end_tp_report 06:16:47 INFO - PID 1645 | __start_cc_report 06:16:47 INFO - PID 1645 | _x_x_mozilla_cycle_collect,427 06:16:47 INFO - PID 1645 | __end_cc_report 06:16:47 INFO - PID 1645 | __startTimestamp1490102207881__endTimestamp 06:16:47 INFO - PID 1645 | 06:16:47 INFO - PID 1645 | ------- Summary: start ------- 06:16:47 INFO - PID 1645 | Number of tests: 6 06:16:47 INFO - PID 1645 | 06:16:47 INFO - PID 1645 | [#0] dojo.html Cycles:165 Average:6116.56 Median:6355.84 stddev:732.43 (11.5%) stddev-sans-first:690.52 06:16:47 INFO - PID 1645 | Values: 2923.7 3058.4 3527.8 3550.1 3612.5 6135.5 6229.3 6234.9 6257.5 6284.9 6134.3 6171.6 6197.5 6200.5 6244.7 5596.5 5657.1 5731.8 5786.7 5820.3 4705.9 5614.1 5662.8 5761.6 5830.1 5532.2 5656.1 5707.7 5759.4 5770.8 5455.8 5497.6 5531.0 5575.9 5576.7 5517.9 5542.3 5555.8 5569.8 5650.2 5152.2 5754.6 6125.1 6253.9 6258.4 6358.0 6438.7 6507.4 6614.0 6636.3 5823.9 6625.1 6781.0 6794.1 6806.1 6197.7 6329.3 6331.7 6355.8 6363.1 6662.2 6748.4 6924.6 6956.3 6975.7 6291.2 6364.2 6367.8 6432.4 6481.1 6578.2 6635.6 6649.1 6650.4 6650.8 6591.0 6602.2 6608.5 6659.1 6661.9 6253.0 6410.0 6670.9 6697.3 6708.0 5244.3 5316.9 5360.1 5384.8 5414.2 4675.0 4687.3 4690.6 4700.4 4731.5 6345.5 6369.8 6370.0 6370.6 6383.5 6447.8 6608.6 6665.1 6704.5 6748.4 5232.5 6027.9 6346.1 6358.3 6361.7 4991.0 5887.3 5897.8 5938.7 5953.9 5824.2 6669.5 6801.6 6829.1 6857.0 6711.7 6719.8 6755.8 6758.4 6804.8 6877.8 6949.3 6952.4 6960.5 6962.9 5888.6 6335.2 6358.5 6593.9 6617.1 6586.7 6647.2 6710.4 6726.5 6751.9 5094.9 6298.8 6521.0 6532.4 6538.4 6201.2 6348.4 6373.3 6375.6 6401.6 5538.3 6177.1 6371.6 6379.4 6398.8 6496.6 6500.9 6526.5 6535.1 6554.4 6241.9 6254.5 6346.5 6382.5 6412.7 06:16:47 INFO - PID 1645 | 06:16:47 INFO - PID 1645 | [#1] ext.html Cycles:180 Average:54338.61 Median:32457.09 stddev:109879.23 (338.5%) stddev-sans-first:110113.36 06:16:47 INFO - PID 1645 | Values: 587.3 807.6 808.7 815.5 841.3 40202.6 41345.8 41367.6 41368.8 42172.6 39815.8 40133.6 40168.0 40222.6 40318.0 2689.9 2746.5 2826.8 2908.3 2913.1 2856.7 2881.0 2899.6 2901.9 2911.3 2869.6 2876.7 2903.2 2907.9 2910.0 2872.8 2904.6 2909.3 2923.9 2924.6 2907.1 2917.2 2923.1 2923.6 2924.8 39407.2 39701.4 39717.6 39752.2 40060.8 3061.9 3065.7 3103.0 3107.1 3127.1 27995.6 28721.0 29007.3 29166.3 29796.9 2786.3 2817.1 2820.3 2821.0 2927.3 342777.0 347502.0 348793.3 348905.0 349437.0 51745.7 51763.2 51831.2 52028.0 52111.7 54024.9 54361.9 54427.0 54667.9 54685.0 14363.9 14624.7 14681.7 14688.3 14709.0 11832.2 11833.6 11844.6 11858.5 11866.6 13333.3 13773.9 13793.0 13794.3 13845.9 5120.7 5179.8 6108.2 6130.7 6180.1 865.5 899.6 978.6 1000.4 1002.8 25054.5 25143.5 28415.4 29155.7 29908.1 921.7 1051.5 1053.2 1080.7 1106.8 12724.6 12876.0 12889.7 12944.8 12972.4 482987.0 485863.0 488593.0 488959.0 489716.0 42021.6 42487.6 57952.4 57978.0 58778.0 1458.2 1472.2 1473.9 1484.3 1490.1 28028.9 28213.0 28866.0 28929.8 29002.4 38865.8 38884.8 38939.2 39119.2 39291.6 389954.0 390182.0 391823.0 393216.0 394905.0 33375.8 34497.0 34836.7 34878.0 34981.8 43657.6 43766.8 43849.6 45237.5 45279.1 35994.9 36545.6 37366.4 37491.0 37791.8 29728.0 34742.0 35894.8 37119.0 37139.0 35434.8 36079.3 36685.6 36975.7 37138.8 30649.4 30854.1 32335.0 32446.5 32467.7 21690.8 22978.0 24041.9 24268.0 25054.6 06:16:47 INFO - PID 1645 | 06:16:47 INFO - PID 1645 | [#2] jquery.html Cycles:180 Average:39834.61 Median:14124.62 stddev:117030.22 (828.6%) stddev-sans-first:117321.02 06:16:47 INFO - PID 1645 | Values: 376.7 384.5 431.6 432.2 452.2 19588.0 20627.0 21206.0 21326.8 21380.4 21243.0 21601.8 22101.0 22118.3 22285.2 4089.8 4276.0 4307.7 4311.3 4312.2 3614.4 4262.8 4277.0 4278.3 4292.8 4075.6 4201.2 4250.8 4254.1 4255.1 4234.7 4237.1 4264.7 4271.1 4272.7 3810.4 3908.7 3922.5 3925.0 3953.8 25814.1 26957.7 27091.6 27166.2 27608.3 1941.3 1955.5 1965.7 1979.9 2010.9 15722.7 16223.9 16289.4 16408.8 17561.2 1699.2 1723.4 1724.5 1756.3 1781.7 148336.3 157617.0 159071.2 159434.2 159970.2 11316.3 12255.2 12503.4 12507.0 12611.0 15768.9 15773.2 15799.4 15800.1 15954.5 8762.8 9019.8 9089.3 9179.8 9306.3 7346.4 8933.9 8935.2 9022.5 9089.3 6626.3 7901.2 7964.8 8030.4 8037.5 2236.1 2241.8 2246.1 2247.3 2435.1 833.1 868.5 911.7 956.8 986.9 18378.3 20393.5 20397.5 20535.5 20839.5 819.7 873.5 919.6 950.0 990.2 9233.9 9445.2 10536.3 10598.6 10750.2 678882.0 694781.0 698114.0 703479.0 719089.0 99534.0 116957.0 147142.0 148037.0 159177.0 1169.3 1205.6 1218.7 1220.6 1225.8 20121.4 20952.5 21974.8 22040.0 22062.3 45863.0 47096.5 53180.9 57176.4 61316.0 90527.2 91307.6 91945.0 91970.0 92512.1 8306.2 8550.4 8592.9 8927.0 8955.2 13823.9 13841.3 13937.4 14229.9 14262.9 11426.0 11645.8 11768.8 11951.3 12144.2 11644.7 11664.8 11710.5 11933.4 12034.6 9927.7 10644.3 11843.9 11990.5 12170.8 8543.4 8639.4 8655.4 8750.7 8847.4 11547.2 11755.7 11822.5 12027.5 12049.3 06:16:47 INFO - PID 1645 | 06:16:47 INFO - PID 1645 | [#3] mootools.html Cycles:180 Average:6609.68 Median:3832.18 stddev:4064.44 (106.1%) stddev-sans-first:4055.33 06:16:47 INFO - PID 1645 | Values: 1175.8 1389.7 1439.2 1441.3 1456.5 9356.7 10325.2 10529.7 10568.5 10582.3 10452.9 10616.4 10630.9 10636.3 10647.9 2381.8 2408.6 2417.1 2421.5 2422.5 2021.5 2387.9 2398.0 2400.8 2401.5 2332.1 2365.2 2372.0 2372.9 2374.6 2310.7 2327.7 2341.3 2342.6 2351.0 2195.8 2236.8 2292.5 2365.0 2390.8 9942.9 10400.8 10458.9 10485.7 10488.5 4860.8 5402.4 5939.4 6226.7 6227.8 3788.8 3800.0 3864.4 3899.2 3928.0 1128.3 1131.4 1154.2 1182.7 1185.3 13478.4 13633.5 14413.6 14759.2 14888.0 5007.4 5032.8 5160.9 5186.6 5187.6 10956.5 11001.8 11017.3 11036.7 11043.5 6127.8 6129.0 6145.3 6154.0 6160.5 5467.7 5521.4 5526.6 5599.2 5686.7 4103.2 4191.8 4208.5 4241.7 4247.8 1126.8 1251.4 1252.7 1263.7 1268.1 746.6 746.9 767.4 772.8 804.7 6336.1 6495.3 6671.8 6689.0 6731.6 743.0 873.1 873.2 889.5 893.6 3515.6 4097.1 4105.7 4187.2 4241.3 13324.0 13640.5 13747.5 13755.3 13866.0 12748.6 13255.4 13724.9 13777.8 13781.0 8849.9 9832.6 10106.1 10117.8 10121.9 5653.3 6450.5 6459.6 6628.9 6710.9 11791.5 11936.7 12031.4 12285.4 12390.7 12260.1 12356.9 12421.0 12542.1 12669.7 8871.2 8886.7 9007.1 9050.0 9100.8 9898.4 9926.9 9950.0 10056.8 10074.3 8657.7 8817.9 8818.8 8879.0 8907.5 8516.7 8561.8 8622.7 8624.7 8638.3 7573.6 7706.5 7746.9 7750.0 7752.5 5117.8 5642.3 5709.6 5765.9 5930.4 5122.3 5348.6 5561.7 5793.5 6078.7 06:16:47 INFO - PID 1645 | 06:16:47 INFO - PID 1645 | [#4] prototype.html Cycles:180 Average:5261.62 Median:5908.64 stddev:1659.43 (28.1%) stddev-sans-first:1622.11 06:16:47 INFO - PID 1645 | Values: 320.3 366.5 367.6 387.3 388.1 5711.5 5906.8 6152.9 6305.8 6352.6 5014.9 5616.9 5966.2 6233.0 6237.2 4775.5 4776.5 4790.7 4795.4 4804.9 3926.2 4234.4 4436.9 4788.3 4790.0 4696.7 4698.7 4726.7 4728.3 4796.3 4349.9 4743.1 4770.7 4777.8 4790.6 3584.4 3657.6 3827.2 3876.8 4179.1 4852.9 5066.0 5805.5 5857.8 5996.5 4500.9 4557.7 5130.1 5255.2 5294.2 5632.3 6249.6 6457.7 6475.3 6510.2 3672.2 3801.2 4215.7 4294.7 4307.6 5558.0 6566.4 6821.4 6883.8 6894.5 3648.0 4111.2 4316.7 4370.0 4377.7 4200.5 4419.0 4421.8 4443.7 4459.4 5256.6 5257.2 5313.2 5313.6 5336.2 6295.0 6343.8 6392.0 6495.9 6573.5 1469.9 1494.8 1497.8 1514.9 1539.5 927.6 956.9 996.9 1002.9 1004.5 6198.2 6250.3 6267.0 6277.9 6338.2 5743.8 6858.7 6859.8 6871.5 6872.4 5017.7 5914.0 5995.6 6218.5 6265.9 2909.2 3417.2 3544.9 3581.3 3583.9 5889.8 6594.2 6719.3 6759.8 6879.1 6934.3 7008.3 7024.0 7069.4 7081.6 6755.3 7038.0 7098.7 7110.5 7160.6 5736.7 6751.0 6754.7 6776.9 6780.2 6200.9 6429.4 6620.1 6759.4 6771.8 5893.0 6304.1 6693.4 7020.5 7024.4 4373.9 4383.5 4390.7 4407.5 4415.8 5910.5 6153.8 6306.4 6427.1 6798.4 6366.8 6601.3 6614.0 6615.9 6621.8 5152.6 6282.6 6351.7 6606.4 6618.7 5857.3 6108.8 6300.8 6413.4 6419.9 5791.4 5930.4 6101.8 6598.6 6638.6 5325.5 6179.3 6402.5 6462.8 6477.1 06:16:47 INFO - PID 1645 | 06:16:47 INFO - PID 1645 | [#5] yui.html Cycles:180 Average:19214.60 Median:38486.44 stddev:28166.39 (73.2%) stddev-sans-first:28210.69 06:16:47 INFO - PID 1645 | Values: 589.1 692.1 696.9 707.7 737.5 14222.2 15012.0 15646.5 15832.8 15836.1 14355.0 14653.9 14708.1 14726.3 15070.2 945.0 972.8 973.7 975.0 975.9 945.5 969.8 986.7 999.2 1000.7 1033.2 1036.5 1037.3 1039.7 1040.3 1015.9 1020.4 1028.8 1030.6 1035.1 757.5 768.7 770.4 770.5 771.8 16139.2 16676.7 16686.1 16861.7 17024.0 14351.9 14591.1 14636.8 14687.9 14780.5 11677.9 11937.6 12099.6 12103.1 12244.5 2997.8 3063.6 3089.1 3169.3 3169.7 88152.0 108301.0 110227.4 110804.0 114298.0 9279.6 10577.4 10970.8 11539.4 11836.9 23844.3 23860.6 24116.4 24189.4 24439.0 7760.3 7843.6 7900.5 7990.4 8151.7 3975.3 3976.6 4062.5 4095.2 4095.8 2991.6 3794.6 4004.2 4017.2 4029.4 780.6 781.2 789.2 790.8 806.9 526.2 541.7 546.7 559.9 565.0 9684.0 9830.6 10022.6 10154.5 10304.0 542.2 558.2 581.7 589.6 595.7 4035.9 4491.3 4579.0 4606.8 4718.8 97854.0 98770.0 102335.0 102714.5 104859.5 87207.1 89475.0 90188.5 91482.1 91953.5 57985.8 62257.7 75469.2 75626.6 76324.6 9226.4 9233.2 9258.8 9301.9 9364.0 38181.2 38791.6 39220.4 39263.4 39297.0 50182.2 50384.2 50491.0 50572.7 50627.7 10161.5 11580.1 11629.5 11634.5 12179.3 10429.4 10570.5 10574.3 10613.3 11401.3 11267.2 11607.6 11611.8 11661.8 12307.3 11455.3 11524.0 11588.8 12104.9 12199.6 11576.9 11727.3 11915.6 12265.4 12341.3 9502.5 9865.7 10000.3 10047.2 10291.2 8782.9 9123.9 9155.7 9764.7 9782.2 06:16:47 INFO - PID 1645 | -------- Summary: end -------- 06:16:47 INFO - PID 1645 | 06:16:48 INFO - TEST-INFO | 1645: exit 0 06:16:48 INFO - TEST-OK | dromaeo_css | took 1117288ms 06:16:48 INFO - TEST-START | kraken 06:16:48 INFO - operating with platform_type : mac_ 06:16:48 INFO - Initialising browser for kraken test... 06:16:48 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmpfvRI8V/profile http://localhost:49242/getInfo.html) 06:16:51 INFO - PID 1657 | 2017-03-21 06:16:51.534 plugin-container[1658:10580] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 06:16:51 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:16:51 INFO - PID 1657 | 2017-03-21 06:16:51.536 plugin-container[1658:10580] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9213, name = 'com.apple.CFPasteboardClient' 06:16:51 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:16:51 INFO - PID 1657 | 2017-03-21 06:16:51.536 plugin-container[1658:10580] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:16:51 INFO - PID 1657 | __metrics Screen width/height:1600/1200 06:16:51 INFO - PID 1657 | colorDepth:24 06:16:51 INFO - PID 1657 | Browser inner width/height: 1024/689 06:16:51 INFO - PID 1657 | __metrics 06:16:51 ERROR - PID 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 06:16:52 INFO - TEST-INFO | 1657: exit 0 06:16:52 INFO - Browser initialized. 06:16:52 INFO - Running cycle 1/1 for kraken test... 06:16:52 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmpfvRI8V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:16:55 INFO - PID 1659 | 2017-03-21 06:16:55.056 plugin-container[1660:10700] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 06:16:55 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:16:55 INFO - PID 1659 | 2017-03-21 06:16:55.056 plugin-container[1660:10700] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 06:16:55 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:16:55 INFO - PID 1659 | 2017-03-21 06:16:55.056 plugin-container[1660:10700] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:17:04 INFO - PID 1659 | RSS: Main: 227287040 06:17:04 INFO - PID 1659 | 06:17:07 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 06:17:08 INFO - PID 1659 | RSS: Main: 241942528 06:17:08 INFO - PID 1659 | 06:17:11 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 06:17:11 INFO - PID 1659 | RSS: Main: 250437632 06:17:11 INFO - PID 1659 | 06:17:15 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 06:17:15 INFO - PID 1659 | RSS: Main: 253104128 06:17:15 INFO - PID 1659 | 06:17:17 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 06:17:18 INFO - PID 1659 | RSS: Main: 255397888 06:17:18 INFO - PID 1659 | 06:17:20 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:17:21 INFO - PID 1659 | RSS: Main: 255733760 06:17:21 INFO - PID 1659 | 06:17:24 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 06:17:24 INFO - PID 1659 | RSS: Main: 261521408 06:17:24 INFO - PID 1659 | 06:17:28 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 06:17:28 INFO - PID 1659 | RSS: Main: 262160384 06:17:28 INFO - PID 1659 | 06:17:31 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 06:17:32 INFO - PID 1659 | RSS: Main: 262307840 06:17:32 INFO - PID 1659 | 06:17:34 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:17:34 INFO - PID 1659 | RSS: Main: 261365760 06:17:34 INFO - PID 1659 | 06:17:36 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:17:37 INFO - PID 1659 | RSS: Main: 261337088 06:17:37 INFO - PID 1659 | 06:17:39 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:17:40 INFO - PID 1659 | RSS: Main: 261447680 06:17:40 INFO - PID 1659 | 06:17:43 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:17:44 INFO - PID 1659 | RSS: Main: 261779456 06:17:44 INFO - PID 1659 | 06:17:47 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:17:47 INFO - PID 1659 | RSS: Main: 261566464 06:17:47 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 06:17:49 INFO - PID 1659 | RSS: Main: 263241728 06:17:49 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | __start_tp_report 06:17:49 INFO - PID 1659 | _x_x_mozilla_page_load 06:17:49 INFO - PID 1659 | _x_x_mozilla_page_load_details 06:17:49 INFO - PID 1659 | |i|pagename|runs| 06:17:49 INFO - PID 1659 | |0;ai-astar;105;117;116;116;123;116;114;117;135;167 06:17:49 INFO - PID 1659 | |1;audio-beat-detection;175;133;134;150;105;124;97;141;172;148 06:17:49 INFO - PID 1659 | |2;audio-dft;196;228;277;250;245;201;195;204;198;193 06:17:49 INFO - PID 1659 | |3;audio-fft;81;61;80;75;58;73;88;54;80;62 06:17:49 INFO - PID 1659 | |4;audio-oscillator;87;69;79;70;71;70;85;71;71;83 06:17:49 INFO - PID 1659 | |5;imaging-gaussian-blur;89;88;90;89;100;136;137;99;89;88 06:17:49 INFO - PID 1659 | |6;imaging-darkroom;96;148;97;101;153;154;99;98;99;99 06:17:49 INFO - PID 1659 | |7;imaging-desaturate;122;99;142;146;90;91;91;90;93;97 06:17:49 INFO - PID 1659 | |8;json-parse-financial;67;67;68;68;71;69;67;67;68;68 06:17:49 INFO - PID 1659 | |9;json-stringify-tinderbox;61;65;67;66;57;59;59;60;58;60 06:17:49 INFO - PID 1659 | |10;stanford-crypto-aes;85;83;84;86;85;84;78;93;112;109 06:17:49 INFO - PID 1659 | |11;stanford-crypto-ccm;143;142;138;139;138;142;155;156;203;148 06:17:49 INFO - PID 1659 | |12;stanford-crypto-pbkdf2;189;192;189;229;191;188;188;189;188;190 06:17:49 INFO - PID 1659 | |13;stanford-crypto-sha256-iterative;69;69;68;69;71;67;70;69;67;70 06:17:49 INFO - PID 1659 | __end_tp_report 06:17:49 INFO - PID 1659 | __start_cc_report 06:17:49 INFO - PID 1659 | _x_x_mozilla_cycle_collect,1044 06:17:49 INFO - PID 1659 | __end_cc_report 06:17:49 INFO - PID 1659 | __startTimestamp1490102269759__endTimestamp 06:17:49 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | ------- Summary: start ------- 06:17:49 INFO - PID 1659 | Number of tests: 14 06:17:49 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | [#0] ai-astar Cycles:10 Average:122.60 Median:117.00 stddev:17.33 (14.8%) stddev-sans-first:17.17 06:17:49 INFO - PID 1659 | Values: 105.0 117.0 116.0 116.0 123.0 116.0 114.0 117.0 135.0 167.0 06:17:49 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | [#1] audio-beat-detection Cycles:10 Average:137.90 Median:149.00 stddev:25.35 (17.0%) stddev-sans-first:23.06 06:17:49 INFO - PID 1659 | Values: 175.0 133.0 134.0 150.0 105.0 124.0 97.0 141.0 172.0 148.0 06:17:49 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | [#2] audio-dft Cycles:10 Average:218.70 Median:216.00 stddev:29.54 (13.7%) stddev-sans-first:30.17 06:17:49 INFO - PID 1659 | Values: 196.0 228.0 277.0 250.0 245.0 201.0 195.0 204.0 198.0 193.0 06:17:49 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | [#3] audio-fft Cycles:10 Average:71.20 Median:77.50 stddev:11.59 (15.0%) stddev-sans-first:11.74 06:17:49 INFO - PID 1659 | Values: 81.0 61.0 80.0 75.0 58.0 73.0 88.0 54.0 80.0 62.0 06:17:49 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:75.00 stddev:7.11 (9.5%) stddev-sans-first:6.22 06:17:49 INFO - PID 1659 | Values: 87.0 69.0 79.0 70.0 71.0 70.0 85.0 71.0 71.0 83.0 06:17:49 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:100.50 Median:89.00 stddev:19.48 (21.9%) stddev-sans-first:20.21 06:17:49 INFO - PID 1659 | Values: 89.0 88.0 90.0 89.0 100.0 136.0 137.0 99.0 89.0 88.0 06:17:49 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | [#6] imaging-darkroom Cycles:10 Average:114.40 Median:97.50 stddev:25.79 (26.5%) stddev-sans-first:26.49 06:17:49 INFO - PID 1659 | Values: 96.0 148.0 97.0 101.0 153.0 154.0 99.0 98.0 99.0 99.0 06:17:49 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | [#7] imaging-desaturate Cycles:10 Average:106.10 Median:91.00 stddev:22.14 (24.3%) stddev-sans-first:22.73 06:17:49 INFO - PID 1659 | Values: 122.0 99.0 142.0 146.0 90.0 91.0 91.0 90.0 93.0 97.0 06:17:49 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | [#8] json-parse-financial Cycles:10 Average:68.00 Median:68.00 stddev:1.25 (1.8%) stddev-sans-first:1.27 06:17:49 INFO - PID 1659 | Values: 67.0 67.0 68.0 68.0 71.0 69.0 67.0 67.0 68.0 68.0 06:17:49 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:60.50 stddev:3.52 (5.8%) stddev-sans-first:3.73 06:17:49 INFO - PID 1659 | Values: 61.0 65.0 67.0 66.0 57.0 59.0 59.0 60.0 58.0 60.0 06:17:49 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:84.50 stddev:11.47 (13.6%) stddev-sans-first:12.03 06:17:49 INFO - PID 1659 | Values: 85.0 83.0 84.0 86.0 85.0 84.0 78.0 93.0 112.0 109.0 06:17:49 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:150.40 Median:145.50 stddev:19.60 (13.5%) stddev-sans-first:20.61 06:17:49 INFO - PID 1659 | Values: 143.0 142.0 138.0 139.0 138.0 142.0 155.0 156.0 203.0 148.0 06:17:49 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.30 Median:189.50 stddev:12.61 (6.7%) stddev-sans-first:13.28 06:17:49 INFO - PID 1659 | Values: 189.0 192.0 189.0 229.0 191.0 188.0 188.0 189.0 188.0 190.0 06:17:49 INFO - PID 1659 | 06:17:49 INFO - PID 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:1.29 (1.9%) stddev-sans-first:1.36 06:17:49 INFO - PID 1659 | Values: 69.0 69.0 68.0 69.0 71.0 67.0 70.0 69.0 67.0 70.0 06:17:49 INFO - PID 1659 | -------- Summary: end -------- 06:17:49 INFO - PID 1659 | 06:17:50 INFO - TEST-INFO | 1659: exit 0 06:17:50 INFO - TEST-OK | kraken | took 62265ms 06:17:50 INFO - SUITE-END | took 1179s 06:17:50 INFO - Completed test suite (00:19:39) 06:17:50 INFO - javascript benchmark 06:17:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7382.711900053841, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2923.722246386595, 3058.3577448735764, 3527.788332700037, 3550.1302180965668, 3612.5123108380362, 6135.478484328826, 6229.345918678536, 6234.906476402852, 6257.468115210207, 6284.937150628503, 6134.2529056856065, 6171.567990240674, 6197.473214776738, 6200.472959798422, 6244.687765611724, 5596.468335508121, 5657.066584013633, 5731.799387021455, 5786.710664466781, 5820.330661973863, 4705.882352941187, 5614.073677843151, 5662.801801936933, 5761.625494342875, 5830.067189249721, 5532.170174473861, 5656.123300888369, 5707.74315155819, 5759.424057594228, 5770.798022069229, 5455.836324910219, 5497.587680923947, 5531.032069387841, 5575.8884822303735, 5576.665400076008, 5517.889642207174, 5542.279503664538, 5555.833325000256, 5569.777208911639, 5650.180723795068, 5152.227165925104, 5754.625949313281, 6125.111858780452, 6253.87492250157, 6258.405451482102, 6357.968210158966, 6438.742450301982, 6507.44686701631, 6613.966930165318, 6636.336366363325, 5823.883522329572, 6625.105610742585, 6781.0, 6794.082798822145, 6806.115205023365, 6197.721102550396, 6329.335419780857, 6331.746730130789, 6355.8411039723915, 6363.077353783676, 6662.167229096363, 6748.392644661906, 6924.619145947021, 6956.339147780953, 6975.720971161097, 6291.245050593958, 6364.236291644939, 6367.808965731036, 6432.388923052303, 6481.09264702942, 6578.177727784027, 6635.568688035262, 6649.135612370457, 6650.3682150195655, 6650.800475985729, 6590.9670451647435, 6602.2407423146, 6608.471322294301, 6659.134312539339, 6661.867482527982, 6253.0, 6409.9679501602195, 6670.866582668319, 6697.296783837627, 6708.0, 5244.31823862903, 5316.893662126813, 5360.088784906575, 5384.784608615611, 5414.18787181918, 4675.0, 4687.2500399936525, 4690.592822153409, 4700.388949436551, 4731.5332247003535, 6345.460635845912, 6369.808905732835, 6370.0, 6370.649614271259, 6383.489320854321, 6447.80656580294, 6608.570442921195, 6665.100211471487, 6704.463642908556, 6748.426383757337, 5232.450592687789, 6027.8848413043725, 6346.11154438379, 6358.33237510064, 6361.745530178833, 4991.0, 5887.322957859816, 5897.7935772247765, 5938.703064846741, 5953.940460595425, 5824.242848429677, 6669.499787515859, 6801.591904485746, 6829.078074459884, 6857.040014398088, 6711.697973591103, 6719.764808231884, 6755.7973260802255, 6758.42553382968, 6804.795856124324, 6877.759278425231, 6949.339812717783, 6952.409045231212, 6960.547564408297, 6962.895556566555, 5888.587798854039, 6335.208098987627, 6358.459530939737, 6593.934060659524, 6617.07360969455, 6586.670666466562, 6647.169103862017, 6710.362515561014, 6726.495512836459, 6751.864962700817, 5094.898102038012, 6298.842528936813, 6520.967395162994, 6532.41208291256, 6538.411542961157, 6201.193844800147, 6348.396902294275, 6373.2989371168205, 6375.649339286384, 6401.583897046677, 5538.280023596906, 6177.104319873503, 6371.649559274284, 6379.393957574023, 6398.840028999312, 6496.610203387811, 6500.902486462613, 6526.510511711545, 6535.117759102461, 6554.410103090745, 6241.906371404523, 6254.530910181846, 6346.460550853045, 6382.489400847828, 6412.743490260524], "value": 6067.600761594582, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.2571197435245, 807.5881300536724, 808.7371604228626, 815.5310696349596, 841.2765022081011, 40202.59797402025, 41345.79327103364, 41367.58632413675, 41368.75893723189, 42172.578274217245, 39815.8055258342, 40133.59866401335, 40168.0, 40222.59777402225, 40317.99205019876, 2689.9462010759726, 2746.533089374811, 2826.7879909006806, 2908.258394109499, 2913.1260621813476, 2856.657201135856, 2881.0, 2899.6375453068367, 2901.9419611607755, 2911.257629304535, 2869.5695645653213, 2876.6835648078695, 2903.2451562593665, 2907.8982235621756, 2909.956350654742, 2872.755815755663, 2904.6078779364834, 2909.2872246299794, 2923.8976635817644, 2924.6051783009234, 2907.0552070576937, 2917.212352664769, 2923.0792300425296, 2923.63454568179, 2924.7806414518996, 39407.21185576301, 39701.40447893284, 39717.602823971676, 39752.20495590101, 40060.79969600133, 3061.8928337508305, 3065.6934306569165, 3102.9844850775603, 3107.0989413070183, 3127.077512133926, 27995.580066299022, 28721.0, 29007.274818129503, 29166.27084322909, 29796.85101574478, 2786.2755683522228, 2817.0844475545528, 2820.3090242890416, 2821.0, 2927.34134819666, 342777.0, 347502.0, 348793.25603372074, 348905.0, 349437.0, 51745.7412712934, 51763.223551646755, 51831.22253166205, 52028.0, 52111.73944130331, 54024.91950160975, 54361.91276174454, 54427.0, 54667.90664186694, 54685.0, 14363.928180359031, 14624.707505850036, 14681.706365872622, 14688.265586720621, 14709.0, 11832.23090499132, 11833.644990650295, 11844.585439509576, 11858.466369013415, 11866.584669536525, 13333.266670333225, 13773.898088152924, 13793.0, 13794.310284485937, 13845.861541384456, 5120.743962801845, 5179.8446046618665, 6108.205933228741, 6130.7241174146375, 6180.072989051679, 865.4807115730637, 899.6446403670514, 978.574320170728, 1000.3547711725896, 1002.8445590933417, 25054.498910022063, 25143.497130057298, 28415.43169136606, 29155.708442915722, 29908.102756917327, 921.7465197070725, 1051.4690081508788, 1053.1785207538132, 1080.708208783624, 1106.80630889594, 12724.618261452171, 12876.03429742773, 12889.742205155844, 12944.805827912589, 12972.416241269166, 482987.0, 485863.0, 488593.0, 488959.0, 489716.0, 42021.579784202375, 42487.57512424898, 57952.4204757947, 57978.0, 58778.0, 1458.1761304862719, 1472.168224952898, 1473.889458290654, 1484.3394689363129, 1490.135721281654, 28028.85985570059, 28213.0, 28865.989690360737, 28929.842806287514, 29002.419951600426, 38865.805670971466, 38884.805575971935, 38939.15912613131, 39119.21761564696, 39291.60708392879, 389954.0, 390182.0, 391823.0, 393216.0, 394905.0, 33375.83312083521, 34497.0, 34836.651633484355, 34878.0, 34981.82509087438, 43657.56342436535, 43766.78116609396, 43849.56150438455, 45237.547624524654, 45279.09441811211, 35994.92015239547, 36545.634543654225, 37366.439503408015, 37491.0, 37791.81104094572, 29728.0, 34742.0, 35894.8205258972, 37119.0, 37139.0, 35434.822825885705, 36079.27841443209, 36685.63314366822, 36975.70585029511, 37138.814305928296, 30649.387012260075, 30854.074377768702, 32335.0, 32446.513302300955, 32467.67532324741, 21690.783092168876, 22978.0, 24041.918113685275, 24268.02927882865, 25054.62418063694], "value": 15678.814280131755, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.6591234932386, 384.52319124285896, 431.5705872656707, 432.23062947952644, 452.20637780694875, 19588.0, 20627.0, 21206.0, 21326.827024513645, 21380.35858924233, 21243.0, 21601.783982160214, 22101.0, 22118.33644990649, 22285.220017299398, 4089.836406543742, 4276.037891474409, 4307.719998200123, 4311.3101903695415, 4312.180685669713, 3614.367485690007, 4262.829486820539, 4276.973526353684, 4278.315469524877, 4292.763897985626, 4075.572064933184, 4201.1807697499, 4250.829966801324, 4254.106637606106, 4255.085156691311, 4234.661227101825, 4237.131388065439, 4264.701470897038, 4271.124419493996, 4272.658187345005, 3810.40938654509, 3908.7263891527605, 3922.5096862892137, 3924.960750392488, 3953.8220780064967, 25814.09650662218, 26957.730422695717, 27091.593626095626, 27166.1850144496, 27608.309792255153, 1941.3011315926256, 1955.5013471564803, 1965.665836807746, 1979.8614097013215, 2010.9095090720953, 15722.685546289125, 16223.945443546132, 16289.429869954494, 16408.83591164085, 17561.20974556135, 1699.1504247876062, 1723.4312676816746, 1724.4912750738563, 1756.2974810075943, 1781.741647461111, 148336.2583187088, 157617.0, 159071.20464397603, 159434.20282898512, 159970.20014899966, 11316.321020738782, 12255.203411778206, 12503.374561306971, 12506.999440044774, 12610.991120710321, 15768.92115539415, 15773.211339432983, 15799.447019354267, 15800.130992795506, 15954.521364359089, 8762.824743505094, 9019.819603608023, 9089.31830112731, 9179.770505737277, 9306.348555601178, 7346.375558077516, 8933.865992010125, 8935.151160639858, 9022.458652480873, 9089.318301127441, 6626.304238055031, 7901.209879012053, 7964.761057168178, 8030.437869349206, 8037.477563958324, 2236.1279101150562, 2241.7982381585734, 2246.0791075658904, 2247.2808701215454, 2435.147698305614, 833.0586437325783, 868.5353335965228, 911.6900253913701, 956.7895063086249, 986.9210463163073, 18378.264869405342, 20393.49016274605, 20397.49006274855, 20535.4866128345, 20839.479013024797, 819.72948926854, 873.5195642396657, 919.6183583812776, 949.9952500237592, 990.153418826907, 9233.861492077624, 9445.244380449547, 10536.32635270351, 10598.629047983284, 10750.247482676294, 678882.0, 694781.0, 698114.0, 703479.0, 719089.0, 99534.0, 116957.0, 147142.0, 148037.0, 159177.0, 1169.2984209474248, 1205.572021932219, 1218.65268398506, 1220.6277085489012, 1225.7977433723513, 20121.39635810928, 20952.533322667266, 21974.780252197274, 22040.008199630734, 22062.33812985613, 45863.0, 47096.529034709216, 53180.936381271385, 57176.42823571711, 61316.0, 90527.2288580022, 91307.63038554294, 91945.0, 91970.0, 92512.07487925034, 8306.210909963544, 8550.401471896908, 8592.945064680915, 8926.955365223132, 8955.194032537103, 13823.930880345533, 13841.307934603028, 13937.372818223, 14229.932755043206, 14262.857371426153, 11426.0, 11645.825312620487, 11768.82346764782, 11951.342676152548, 12144.210626309261, 11644.6506604802, 11664.825027624762, 11710.473028713557, 11933.403329833647, 12034.63896083119, 9927.652532161628, 10644.308119972176, 11843.881561184278, 11990.460429280529, 12170.77474469284, 8543.401961862859, 8639.352048596254, 8655.394122411371, 8750.737477875675, 8847.36818478813, 11547.249428787101, 11755.706107347043, 11822.527098915947, 12027.518899244282, 12049.33728644933], "value": 9851.958542401519, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1175.794810319423, 1389.6734267447148, 1439.2012433099633, 1441.3441883942808, 1456.5120684570672, 9356.71929842105, 10325.173986081114, 10529.684109476719, 10568.471576421167, 10582.312149710264, 10452.947735261332, 10616.363018218894, 10630.946845265762, 10636.255462117655, 10647.935206479347, 2381.7737314955093, 2408.590539608262, 2417.057347634424, 2421.5278020785954, 2422.527607116613, 2021.5451523407262, 2387.916422925198, 2398.0, 2400.7719266669724, 2401.4836810085826, 2332.11379675723, 2365.1603680693365, 2371.988140059297, 2372.857628542284, 2374.6319320505263, 2310.676505289261, 2327.674125622414, 2341.285907798121, 2342.590046741822, 2350.9882450587725, 2195.769444208367, 2236.848023268018, 2292.518571100071, 2364.97635023651, 2390.796782273509, 9942.90057099427, 10400.84398734012, 10458.947705261426, 10485.737856553571, 10488.475576221159, 4860.781264843091, 5402.405735369106, 5939.406059394069, 6226.719797609118, 6227.813165604994, 3788.8484460621544, 3799.9930018545106, 3864.3623802072625, 3899.200663863901, 3927.9607203928167, 1128.266626692648, 1131.4173200801595, 1154.1920655541155, 1182.6924999500106, 1185.3362117214388, 13478.393472293672, 13633.522826701117, 14413.639659008608, 14759.18824464654, 14888.0, 5007.37407824022, 5032.823851165192, 5160.948390516121, 5186.559142472856, 5187.610929180251, 10956.452177391098, 11001.834972475419, 11017.338959662446, 11036.668899933014, 11043.503042363112, 6127.754889804358, 6128.9693551531955, 6145.324014358417, 6153.969230153821, 6160.507159427235, 5467.699276539749, 5521.365043020024, 5526.613137080446, 5599.188117722907, 5686.748915238641, 4103.199876024206, 4191.790410479463, 4208.516020657664, 4241.660667146621, 4247.8088486018205, 1126.8478755368085, 1251.4226359393776, 1252.7244006318595, 1263.6588121207224, 1268.1376663868473, 746.5707218349362, 746.9365103966223, 767.4359345880773, 772.8415674786655, 804.6781287485053, 6336.144620476269, 6495.3179916109075, 6671.799846004627, 6688.996650502463, 6731.562448440934, 742.9591372474566, 873.1355957602055, 873.2010210657304, 889.5374405309333, 893.5532233883058, 3515.648435156464, 4097.09863829957, 4105.733127346773, 4187.183499217685, 4241.321388577812, 13324.0, 13640.522581709592, 13747.450101996008, 13755.31223438824, 13866.0, 12748.61754147377, 13255.403506842214, 13724.93137534306, 13777.793333100011, 13781.0, 8849.91150088491, 9832.557534910946, 10106.140978016803, 10117.797644047077, 10121.949390253001, 5653.293338332708, 6450.491435269139, 6459.580127291711, 6628.900566491603, 6710.932890671031, 11791.528338866348, 11936.701582460508, 12031.398430078287, 12285.38573071325, 12390.690232743893, 12260.080493963167, 12356.876431235933, 12421.0063194946, 12542.059345548938, 12669.74660506803, 8871.201591856763, 8886.688965886164, 9007.144321289466, 9050.049744776703, 9100.77248068777, 9898.356606820533, 9926.851097233402, 9950.016628033456, 10056.798864022825, 10074.294799363974, 8657.696980605648, 8817.911820881965, 8818.779530511789, 8878.95560522219, 8907.465552066897, 8516.659333626585, 8561.82876342482, 8622.741317760478, 8624.741257762276, 8638.26574741154, 7573.583452910143, 7706.460547761827, 7746.92253077462, 7750.0, 7752.496087754278, 5117.820876269313, 5642.322921249476, 5709.600327977002, 5765.913511297418, 5930.377310382348, 5122.334096567422, 5348.625596208228, 5561.6663000220115, 5793.536517078539, 6078.696065196811], "value": 5043.5862454615235, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.34809163352577, 366.46862050027465, 367.59748075856925, 387.29512287636504, 388.1325238092864, 5711.515490761516, 5906.755432935479, 6152.907706384397, 6305.7792977245745, 6352.555321127517, 5014.9498505014935, 5616.859578510529, 5966.224390829197, 6233.005438259759, 6237.189165408502, 4775.474697783241, 4776.498467660897, 4790.712557246559, 4795.424549054118, 4804.855854324358, 3926.155876486555, 4234.364845273203, 4436.911261774762, 4788.329633851264, 4789.97015641637, 4696.741679207642, 4698.68436837686, 4726.716397016173, 4728.314394412808, 4796.328514008042, 4349.891252718676, 4743.075100355432, 4770.713757174546, 4777.784999675023, 4790.616750659956, 3584.426491761306, 3657.579378371468, 3827.158025234444, 3876.7673939563715, 4179.080602267496, 4852.8544143675745, 5065.974670126626, 5805.535557155417, 5857.824265272048, 5996.4902983246475, 4500.864974050751, 4557.723837325521, 5130.127878260705, 5255.185446255837, 5294.205869119624, 5632.295963004624, 6249.5625306228585, 6457.677116144174, 6475.320091390383, 6510.1536800216145, 3672.2288319452946, 3801.182745709658, 4215.650991682663, 4294.656427485794, 4307.569243075667, 5557.999560079184, 6566.441852442548, 6821.386075253251, 6883.793486195422, 6894.517383783137, 3648.0, 4111.232170166797, 4316.719413238129, 4369.978150109293, 4377.671674624416, 4200.474940632421, 4418.977905110454, 4421.756803375781, 4443.733375997451, 4459.420275364182, 5256.605754568346, 5257.237700533477, 5313.1764576490705, 5313.601479888946, 5336.17289320156, 6294.9685251574365, 6343.809685709436, 6392.0, 6495.8700825983215, 6573.474122070223, 1469.8530146985215, 1494.8340294570253, 1497.7903093566908, 1514.9091054536543, 1539.538138558428, 927.5547737086237, 956.933014688979, 996.9102780749768, 1002.8567433126242, 1004.5379125602304, 6198.163247961557, 6250.343713909974, 6266.968665156736, 6277.8744425111245, 6338.207724034496, 5743.798967036134, 6858.6570671466225, 6859.759908403182, 6871.4502839772695, 6872.381485666314, 5017.724025178577, 5914.0, 5995.640261584319, 6218.471429928416, 6265.8746825063245, 2909.2253725227592, 3417.2311229973056, 3544.9468257975636, 3581.3374525712825, 3583.9283214336087, 5889.764409423575, 6594.20869495654, 6719.328067193339, 6759.797206083826, 6879.140107486564, 6934.271901450376, 7008.299170082951, 7024.0, 7069.363757261871, 7081.575105493584, 6755.290694477009, 7037.964810175917, 7098.68055937484, 7110.466714996396, 7160.606166660779, 5736.741846616996, 6751.0, 6754.729810807709, 6776.8652874344425, 6780.1524809398825, 6200.875982480416, 6429.38920802523, 6620.073189753342, 6759.357861003196, 6771.762988295386, 5893.027650437802, 6304.085907543289, 6693.397594216543, 7020.508564400443, 7024.438044956493, 4373.890652733707, 4383.495897971774, 4390.692651514491, 4407.4711034674765, 4415.757133357696, 5910.52715782745, 6153.8307721532765, 6306.432421082125, 6427.132337134427, 6798.4221341184575, 6366.808995730135, 6601.33986601336, 6614.0, 6615.933840661724, 6621.834454138685, 5152.62503060496, 6282.591631543935, 6351.745930162741, 6606.43845273138, 6618.7352505899225, 5857.26784151981, 6108.75564977396, 6300.779472718433, 6413.422791948747, 6419.935800642121, 5791.362950075572, 5930.377310382521, 6101.84745381369, 6598.5710928791395, 6638.5684930479365, 5325.467453254643, 6179.28938172116, 6402.455791257609, 6462.773802916875, 6477.093206950936], "value": 4732.073080336238, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.0622524948519, 692.1071817355613, 696.9058578032491, 707.731062196365, 737.5095561451635, 14222.217778022205, 15012.0, 15646.452374166907, 15832.841671583281, 15836.129012904314, 14355.0, 14653.92673036636, 14708.117512949231, 14726.26368681567, 15070.246487675628, 945.0360632155198, 972.8248915195262, 973.7322236385015, 974.9707508774748, 975.878015248094, 945.4563625915092, 969.8205831921082, 986.6743974488437, 999.2256001598746, 1000.67978246961, 1033.230243468617, 1036.471399586209, 1037.2531777120462, 1039.7452624107104, 1040.3185913226848, 1015.8577799108131, 1020.4489575629151, 1028.7685270814045, 1030.6032177611598, 1035.1201478743083, 757.4773406349601, 768.673313841615, 770.4491288728586, 770.5415277909635, 771.834055678032, 16139.193040348053, 16676.6664666706, 16686.082265475394, 16861.747073793904, 17024.0, 14351.856481435156, 14591.05158164716, 14636.780448293284, 14687.926560367237, 14780.482683106147, 11677.88322116785, 11937.582184623583, 12099.62990895066, 12103.113170799877, 12244.510219591206, 2997.760179185682, 3063.6476805167467, 3089.1350421881684, 3169.255225022118, 3169.6513383527795, 88152.0, 108301.0, 110227.44886275598, 110804.0, 114298.0, 9279.628814847465, 10577.418241996764, 10970.835437468444, 11539.365334906493, 11836.881631183578, 23844.284671459885, 23860.64209036866, 24116.397090072536, 24189.395265118514, 24439.02243910259, 7760.30157285847, 7843.56860372685, 7900.525968441912, 7990.4007199460275, 8151.714689985941, 3975.284448799244, 3976.622220889011, 4062.471878655756, 4095.201435720006, 4095.836166553364, 2991.5512673098774, 3794.582595914447, 4004.2191772604065, 4017.196560687874, 4029.4157347184496, 780.6331024418514, 781.1758594682665, 789.1753117991713, 790.8022994251437, 806.8870358149863, 526.2264471227367, 541.7101850509958, 546.7184400033985, 559.8936202121583, 564.9717514124277, 9683.951580242054, 9830.606775728891, 10022.649207277711, 10154.52757368422, 10304.0, 542.1975476295105, 558.1683291895047, 581.7440326256434, 589.5519405252039, 595.684287327717, 4035.858744943913, 4491.326301054868, 4579.0, 4606.792694328763, 4718.834840780556, 97854.04291914121, 98770.0, 102335.0, 102714.48642756739, 104859.475702621, 87207.1279287199, 89475.0, 90188.54905725429, 91482.0851791487, 91953.54023229975, 57985.8402831941, 62257.68871155706, 75469.24530754623, 75626.62186689032, 76324.61837690887, 9226.35415520907, 9233.215176710055, 9258.814823703353, 9301.906980930375, 9363.953180234055, 38181.23637527179, 38791.64229252075, 39220.41169382518, 39263.41104883372, 39297.01757455972, 50182.24362147307, 50384.24423633575, 50490.990180195455, 50572.735681608254, 50627.74686126693, 10161.491925403552, 11580.131490138105, 11629.47667354988, 11634.476448559666, 12179.269243845043, 10429.426381549089, 10570.471476425993, 10574.312669676447, 10613.257072004886, 11401.315921044765, 11267.154963377616, 11607.593734219261, 11611.767764644828, 11661.825072624088, 12307.323097229739, 11455.312681239153, 11524.0, 11588.826167607323, 12104.939475302566, 12199.573014944433, 11576.88423115758, 11727.296362218294, 11915.582954596548, 12265.448054837732, 12341.321227332224, 9502.524873756147, 9865.704028879145, 10000.2999790014, 10047.246456515937, 10291.176705863363, 8782.868256976022, 9123.863142052742, 9155.725328240163, 9764.707058788248, 9782.217422606316], "value": 7238.083045601026, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1578.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 116.0, 116.0, 123.0, 116.0, 114.0, 117.0, 135.0, 167.0], "value": 122.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 133.0, 134.0, 150.0, 105.0, 124.0, 97.0, 141.0, 172.0, 148.0], "value": 137.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 228.0, 277.0, 250.0, 245.0, 201.0, 195.0, 204.0, 198.0, 193.0], "value": 218.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 80.0, 75.0, 58.0, 73.0, 88.0, 54.0, 80.0, 62.0], "value": 71.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 79.0, 70.0, 71.0, 70.0, 85.0, 71.0, 71.0, 83.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 90.0, 89.0, 100.0, 136.0, 137.0, 99.0, 89.0, 88.0], "value": 100.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 148.0, 97.0, 101.0, 153.0, 154.0, 99.0, 98.0, 99.0, 99.0], "value": 114.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 99.0, 142.0, 146.0, 90.0, 91.0, 91.0, 90.0, 93.0, 97.0], "value": 106.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 68.0, 71.0, 69.0, 67.0, 67.0, 68.0, 68.0], "value": 68.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 67.0, 66.0, 57.0, 59.0, 59.0, 60.0, 58.0, 60.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 84.0, 86.0, 85.0, 84.0, 78.0, 93.0, 112.0, 109.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 142.0, 138.0, 139.0, 138.0, 142.0, 155.0, 156.0, 203.0, 148.0], "value": 150.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 192.0, 189.0, 229.0, 191.0, 188.0, 188.0, 189.0, 188.0, 190.0], "value": 193.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 69.0, 71.0, 67.0, 70.0, 69.0, 67.0, 70.0], "value": 68.9, "unit": "score"}]}]} 06:17:51 INFO - Return code: 0 06:17:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:17:51 ERROR - # TBPL SUCCESS # 06:17:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:17:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:17:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:17:51 INFO - 2017-03-21 06:17:51.098 screenresolution[1664:11099] starting screenresolution argv=screenresolution get 06:17:51 INFO - 2017-03-21 06:17:51.100 screenresolution[1664:11099] Display 0: 1600x1200x32@60 06:17:51 INFO - 2017-03-21 06:17:51.107 screenresolution[1665:11102] starting screenresolution argv=screenresolution list 06:17:51 INFO - Available Modes on Display 0 06:17:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:17:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:17:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:17:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:17:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:17:51 INFO - Intel Iris: 06:17:51 INFO - Chipset Model: Intel Iris 06:17:51 INFO - Type: GPU 06:17:51 INFO - Bus: Built-In 06:17:51 INFO - VRAM (Dynamic, Max): 1536 MB 06:17:51 INFO - Vendor: Intel (0x8086) 06:17:51 INFO - Device ID: 0x0a2e 06:17:51 INFO - Revision ID: 0x0009 06:17:51 INFO - Displays: 06:17:51 INFO - Display: 06:17:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:17:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:17:51 INFO - Display Serial Number: 1600x1200 60 06:17:51 INFO - Main Display: Yes 06:17:51 INFO - Mirror: Off 06:17:51 INFO - Online: Yes 06:17:51 INFO - Rotation: Supported 06:17:51 INFO - Return code: 0 06:17:51 INFO - Running post-action listener: _resource_record_post_action 06:17:51 INFO - [mozharness: 2017-03-21 13:17:51.354986Z] Finished run-tests step (success) 06:17:51 INFO - Running post-run listener: _resource_record_post_run 06:17:51 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 39221760; Write bytes: 517048320; Read time: 252; Write time: 1635 06:17:51 INFO - TinderboxPrint: CPU usage
54.3% 06:17:51 INFO - TinderboxPrint: I/O read bytes / time
39,221,760 / 252 06:17:51 INFO - TinderboxPrint: I/O write bytes / time
517,048,320 / 1,635 06:17:51 INFO - TinderboxPrint: CPU idle
2,194.5 (45.7%) 06:17:51 INFO - TinderboxPrint: CPU system
966.1 (20.1%) 06:17:51 INFO - TinderboxPrint: CPU user
1,641.1 (34.2%) 06:17:51 INFO - TinderboxPrint: Swap in / out
249,864,192 / 0 06:17:51 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204028416; Write bytes: 204634112; Read time: 15593; Write time: 445 06:17:51 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 37272064; Write bytes: 301608960; Read time: 234; Write time: 1155 06:17:51 INFO - Running post-run listener: _upload_blobber_files 06:17:51 INFO - Blob upload gear active. 06:17:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:17:51 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 06:17:51 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'] 06:17:51 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 06:17:52 INFO - (blobuploader) - INFO - Open directory for files ... 06:17:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:17:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:17:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:53 INFO - (blobuploader) - INFO - Done attempting. 06:17:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:17:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:17:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:54 INFO - (blobuploader) - INFO - Done attempting. 06:17:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:17:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:17:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:55 INFO - (blobuploader) - INFO - Done attempting. 06:17:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:17:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:17:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:57 INFO - (blobuploader) - INFO - Done attempting. 06:17:57 INFO - (blobuploader) - INFO - Iteration through files over. 06:17:57 INFO - Return code: 0 06:17:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:17:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:17:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2025cbeb753b5372666b9d99246cf6a514171f8932e2a1f72cecde40a845d2b73987c0bbf98b6bc7dc0f612c2151c4b1116021df89639b7d48c0b256c8a6c4f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce561882e92912a7042c1ac41d6d62c5f81285de0535462d462cf608bc57829e9a586d0a09f31ef227003a872341fddf34493268964df74592b7bdfabb8a048c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25bf45a1b32674cc55a10a4538393c5ca5724f0a4c8ba5c862a3978bba541c28434d01afd3ff2c92343c012403e1c789c4cab739bd5afa13f2a0212c74561d39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0944afeefa4b606a6d2c1b1c473915457a012e68dc3fd4ce38182d4c105d47f1554cf3d294fe3cf4ce70181133c3a97b2d1e226551ac882c05b6612f59876961"} 06:17:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:17:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:17:57 INFO - Contents: 06:17:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2025cbeb753b5372666b9d99246cf6a514171f8932e2a1f72cecde40a845d2b73987c0bbf98b6bc7dc0f612c2151c4b1116021df89639b7d48c0b256c8a6c4f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce561882e92912a7042c1ac41d6d62c5f81285de0535462d462cf608bc57829e9a586d0a09f31ef227003a872341fddf34493268964df74592b7bdfabb8a048c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25bf45a1b32674cc55a10a4538393c5ca5724f0a4c8ba5c862a3978bba541c28434d01afd3ff2c92343c012403e1c789c4cab739bd5afa13f2a0212c74561d39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0944afeefa4b606a6d2c1b1c473915457a012e68dc3fd4ce38182d4c105d47f1554cf3d294fe3cf4ce70181133c3a97b2d1e226551ac882c05b6612f59876961"} 06:17:57 INFO - Running post-run listener: copy_logs_to_upload_dir 06:17:57 INFO - Copying logs to upload dir... 06:17:57 INFO - mkdir: /builds/slave/test/build/upload/logs 06:17:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.752919 ========= master_lag: 0.36 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-21 06:17:58.133686) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 06:17:58.137216) ========= 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.VWj9piDNyG/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.RJZbIpgV9Z/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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/a2025cbeb753b5372666b9d99246cf6a514171f8932e2a1f72cecde40a845d2b73987c0bbf98b6bc7dc0f612c2151c4b1116021df89639b7d48c0b256c8a6c4f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce561882e92912a7042c1ac41d6d62c5f81285de0535462d462cf608bc57829e9a586d0a09f31ef227003a872341fddf34493268964df74592b7bdfabb8a048c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25bf45a1b32674cc55a10a4538393c5ca5724f0a4c8ba5c862a3978bba541c28434d01afd3ff2c92343c012403e1c789c4cab739bd5afa13f2a0212c74561d39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0944afeefa4b606a6d2c1b1c473915457a012e68dc3fd4ce38182d4c105d47f1554cf3d294fe3cf4ce70181133c3a97b2d1e226551ac882c05b6612f59876961"} build_url:https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010561 build_url: 'https://queue.taskcluster.net/v1/task/VbJeefKLQnKKy8W7Kwfv0w/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/a2025cbeb753b5372666b9d99246cf6a514171f8932e2a1f72cecde40a845d2b73987c0bbf98b6bc7dc0f612c2151c4b1116021df89639b7d48c0b256c8a6c4f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce561882e92912a7042c1ac41d6d62c5f81285de0535462d462cf608bc57829e9a586d0a09f31ef227003a872341fddf34493268964df74592b7bdfabb8a048c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25bf45a1b32674cc55a10a4538393c5ca5724f0a4c8ba5c862a3978bba541c28434d01afd3ff2c92343c012403e1c789c4cab739bd5afa13f2a0212c74561d39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0944afeefa4b606a6d2c1b1c473915457a012e68dc3fd4ce38182d4c105d47f1554cf3d294fe3cf4ce70181133c3a97b2d1e226551ac882c05b6612f59876961"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 06:17:58.180913) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 06:17:58.181600) ========= 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.VWj9piDNyG/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.RJZbIpgV9Z/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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.005262 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 06:17:58.234397) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 06:17:58.235129) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 06:17:58.235846) ========= ========= Total master_lag: 0.51 =========