builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0188 starttime: 1492690543.39 results: success (0) buildid: 20170420030346 builduid: f33f324b2ee049dc9df23bee30d0bcc0 revision: 27311156637f9b5d4504373967e01c4241902ae7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-20 05:15:43.392488) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-20 05:15:43.392977) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-20 05:15:43.393281) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-20 05:15:43.419677) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 05:15:43.420076) ========= 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.wfl2YvDoRY/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.9hTqI3oYf7/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-20 05:15:43-- 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% 78.5M=0s 2017-04-20 05:15:43 (78.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.087668 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 05:15:43.532313) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 05:15:43.532646) ========= 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.wfl2YvDoRY/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.9hTqI3oYf7/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.028528 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 05:15:43.583354) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-20 05:15:43.583709) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 27311156637f9b5d4504373967e01c4241902ae7 --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 27311156637f9b5d4504373967e01c4241902ae7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wfl2YvDoRY/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.9hTqI3oYf7/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-20 05:15:43,686 truncating revision to first 12 chars 2017-04-20 05:15:43,686 Setting DEBUG logging. 2017-04-20 05:15:43,686 attempt 1/10 2017-04-20 05:15:43,686 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/27311156637f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-20 05:15:45,110 unpacking tar archive at: mozilla-central-27311156637f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.727590 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-20 05:15:45.333332) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-20 05:15:45.333664) ========= script_repo_revision: 27311156637f9b5d4504373967e01c4241902ae7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-20 05:15:45.334082) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-20 05:15:45.334391) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-20 05:15:45.352575) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-20 05:15:45.352974) ========= /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.wfl2YvDoRY/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.9hTqI3oYf7/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:15:45 INFO - MultiFileLogger online at 20170420 05:15:45 in /builds/slave/test 05:15:45 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:15:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:15:45 INFO - {'append_to_log': False, 05:15:45 INFO - 'base_work_dir': '/builds/slave/test', 05:15:45 INFO - 'blob_upload_branch': 'Firefox', 05:15:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:15:45 INFO - 'branch': 'Firefox', 05:15:45 INFO - 'buildbot_json_path': 'buildprops.json', 05:15:45 INFO - 'config_files': ('talos/mac_config.py',), 05:15:45 INFO - 'default_actions': ('clobber', 05:15:45 INFO - 'read-buildbot-config', 05:15:45 INFO - 'download-and-extract', 05:15:45 INFO - 'populate-webroot', 05:15:45 INFO - 'create-virtualenv', 05:15:45 INFO - 'install', 05:15:45 INFO - 'run-tests'), 05:15:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:15:45 INFO - 'download_minidump_stackwalk': True, 05:15:45 INFO - 'download_symbols': 'ondemand', 05:15:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:15:45 INFO - 'tooltool.py': '/tools/tooltool.py', 05:15:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:15:45 INFO - '/tools/misc-python/virtualenv.py')}, 05:15:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:15:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:15:45 INFO - 'gecko_profile': False, 05:15:45 INFO - 'gecko_profile_interval': 0, 05:15:45 INFO - 'installer_path': 'installer.exe', 05:15:45 INFO - 'log_level': 'info', 05:15:45 INFO - 'log_name': 'talos', 05:15:45 INFO - 'log_to_console': True, 05:15:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:15:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:15:45 INFO - 'opt_config_files': (), 05:15:45 INFO - 'pip_index': False, 05:15:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:15:45 INFO - 'cmd': ('bash', 05:15:45 INFO - '-c', 05:15:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:15:45 INFO - 'enabled': True, 05:15:45 INFO - 'halt_on_failure': False, 05:15:45 INFO - 'name': 'check_screen_resolution'},), 05:15:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:15:45 INFO - 'cmd': ('bash', 05:15:45 INFO - '-c', 05:15:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:15:45 INFO - 'enabled': True, 05:15:45 INFO - 'halt_on_failure': False, 05:15:45 INFO - 'name': 'check_screen_resolution'},), 05:15:45 INFO - 'run_cmd_checks_enabled': True, 05:15:45 INFO - 'suite': 'dromaeojs-e10s', 05:15:45 INFO - 'system_bits': '32', 05:15:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:15:45 INFO - 'title': 't-yosemite-r7-0188', 05:15:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:15:45 INFO - 'use_talos_json': True, 05:15:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:15:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:15:45 INFO - 'work_dir': 'build'} 05:15:45 INFO - [mozharness: 2017-04-20 12:15:45.560738Z] Running clobber step. 05:15:45 INFO - Running pre-action listener: _resource_record_pre_action 05:15:45 INFO - Running main action method: clobber 05:15:45 INFO - rmtree: /builds/slave/test/build 05:15:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:15:48 INFO - Running post-action listener: _resource_record_post_action 05:15:48 INFO - [mozharness: 2017-04-20 12:15:48.104548Z] Finished clobber step (success) 05:15:48 INFO - [mozharness: 2017-04-20 12:15:48.104717Z] Running read-buildbot-config step. 05:15:48 INFO - Running pre-action listener: _resource_record_pre_action 05:15:48 INFO - Running main action method: read_buildbot_config 05:15:48 INFO - Using buildbot properties: 05:15:48 INFO - { 05:15:48 INFO - "project": "", 05:15:48 INFO - "product": "firefox", 05:15:48 INFO - "script_repo_revision": "production", 05:15:48 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:15:48 INFO - "repository": "", 05:15:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:15:48 INFO - "buildid": "20170420030346", 05:15:48 INFO - "stage_platform": "macosx64", 05:15:48 INFO - "basedir": "/builds/slave/test", 05:15:48 INFO - "nightly_build": "True", 05:15:48 INFO - "buildnumber": 30, 05:15:48 INFO - "slavename": "t-yosemite-r7-0188", 05:15:48 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 05:15:48 INFO - "platform": "yosemite_r7", 05:15:48 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:15:48 INFO - "branch": "mozilla-central", 05:15:48 INFO - "repo_path": "mozilla-central", 05:15:48 INFO - "revision": "27311156637f9b5d4504373967e01c4241902ae7", 05:15:48 INFO - "pgo_build": "False", 05:15:48 INFO - "builduid": "f33f324b2ee049dc9df23bee30d0bcc0", 05:15:48 INFO - "slavebuilddir": "test" 05:15:48 INFO - } 05:15:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 05:15:48 INFO - Running post-action listener: _resource_record_post_action 05:15:48 INFO - [mozharness: 2017-04-20 12:15:48.108149Z] Finished read-buildbot-config step (success) 05:15:48 INFO - [mozharness: 2017-04-20 12:15:48.108286Z] Running download-and-extract step. 05:15:48 INFO - Running pre-action listener: _resource_record_pre_action 05:15:48 INFO - Running main action method: download_and_extract 05:15:48 INFO - mkdir: /builds/slave/test/build/tests 05:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:48 INFO - trying https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:15:48 INFO - Downloading https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/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:15:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/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:15:49 INFO - Downloaded 1325 bytes. 05:15:49 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:15:49 INFO - Using the following test package requirements: 05:15:49 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:15:49 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:15:49 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:15:49 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:15:49 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:15:49 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:15:49 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:15:49 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:15:49 INFO - u'jsshell-mac.zip'], 05:15:49 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:15:49 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:15:49 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:15:49 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:15:49 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:15:49 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:15:49 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:15:49 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:15:49 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:15:49 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:15:49 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:15:49 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:15:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:15:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:15:49 INFO - Fetch https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:15:50 INFO - Expected file size: 52525041 05:15:50 INFO - Obtained file size: 52525041 05:16:07 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:16:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:16:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:16:07 INFO - Fetch https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:16:09 INFO - Expected file size: 52525041 05:16:09 INFO - Obtained file size: 52525041 05:16:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:16:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:16:24 INFO - Fetch https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:16:25 INFO - Expected file size: 14408811 05:16:25 INFO - Obtained file size: 14408811 05:16: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:16:26 INFO - trying https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:16:26 INFO - Downloading https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:16:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/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:16:28 INFO - Downloaded 71063554 bytes. 05:16:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:16:28 INFO - mkdir: /builds/slave/test/properties 05:16:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:16:28 INFO - Writing to file /builds/slave/test/properties/build_url 05:16:28 INFO - Contents: 05:16:28 INFO - build_url:https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:16:29 INFO - Running post-action listener: _resource_record_post_action 05:16:29 INFO - Running post-action listener: set_extra_try_arguments 05:16:29 INFO - [mozharness: 2017-04-20 12:16:29.828247Z] Finished download-and-extract step (success) 05:16:29 INFO - [mozharness: 2017-04-20 12:16:29.828371Z] Running populate-webroot step. 05:16:29 INFO - Running pre-action listener: _resource_record_pre_action 05:16:29 INFO - Running main action method: populate_webroot 05:16:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:16:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:16:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:16:29 INFO - '--tppagecycles', 05:16:29 INFO - '7'], 05:16:29 INFO - 'tests': ['tsvgm']}}, 05:16:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:16:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:16:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:16:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:16:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:16:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:16:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:16:29 INFO - 'talos_options': ['--disable-e10s'], 05:16:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:16:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:16:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:16:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:16:29 INFO - 'talos_options': ['--disable-e10s'], 05:16:29 INFO - 'tests': ['damp', 'tps']}, 05:16:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:16:29 INFO - 'tests': ['damp', 'tps']}, 05:16:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:16:29 INFO - 'tests': ['dromaeo_dom']}, 05:16:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:16:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:16:29 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 05:16:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:16:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:16:29 INFO - 'tests': ['a11yr', 05:16:29 INFO - 'ts_paint', 05:16:29 INFO - 'tpaint', 05:16:29 INFO - 'sessionrestore', 05:16:29 INFO - 'sessionrestore_no_auto_restore', 05:16:29 INFO - 'tabpaint']}, 05:16:29 INFO - 'other-e10s': {'tests': ['a11yr', 05:16:29 INFO - 'ts_paint', 05:16:29 INFO - 'tpaint', 05:16:29 INFO - 'sessionrestore', 05:16:29 INFO - 'sessionrestore_no_auto_restore', 05:16:29 INFO - 'tabpaint']}, 05:16:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:16:29 INFO - 'ts_paint', 05:16:29 INFO - 'tpaint', 05:16:29 INFO - 'sessionrestore', 05:16:29 INFO - 'sessionrestore_no_auto_restore', 05:16:29 INFO - 'tabpaint']}, 05:16:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:16:29 INFO - 'ts_paint', 05:16:29 INFO - 'tpaint', 05:16:29 INFO - 'sessionrestore', 05:16:29 INFO - 'sessionrestore_no_auto_restore', 05:16:29 INFO - 'tabpaint']}, 05:16:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:16:29 INFO - 'tests': ['a11yr', 05:16:29 INFO - 'ts_paint', 05:16:29 INFO - 'tpaint', 05:16:29 INFO - 'sessionrestore', 05:16:29 INFO - 'sessionrestore_no_auto_restore', 05:16:29 INFO - 'tabpaint']}, 05:16:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:16:29 INFO - 'tests': ['a11yr', 05:16:29 INFO - 'ts_paint', 05:16:29 INFO - 'tpaint', 05:16:29 INFO - 'sessionrestore', 05:16:29 INFO - 'sessionrestore_no_auto_restore', 05:16:29 INFO - 'tabpaint']}, 05:16:29 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 05:16:29 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 05:16:29 INFO - 'bloom_basic_ref']}, 05:16:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:16:29 INFO - 'tests': ['tsvgx', 05:16:29 INFO - 'tsvgr_opacity', 05:16:29 INFO - 'tart', 05:16:29 INFO - 'tscrollx', 05:16:29 INFO - 'cart', 05:16:29 INFO - 'tsvg_static']}, 05:16:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:16:29 INFO - 'tsvgr_opacity', 05:16:29 INFO - 'tart', 05:16:29 INFO - 'tscrollx', 05:16:29 INFO - 'cart', 05:16:29 INFO - 'tsvg_static']}, 05:16:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:16:29 INFO - 'talos_options': ['--disable-e10s'], 05:16:29 INFO - 'tests': ['tp5o']}, 05:16:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:16:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:16:29 INFO - 'talos_options': ['--disable-e10s', 05:16:29 INFO - '--xperf_path', 05:16:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:16:29 INFO - 'tests': ['tp5n']}, 05:16:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:16:29 INFO - 'talos_options': ['--xperf_path', 05:16:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:16:29 INFO - 'tests': ['tp5n']}}, 05:16:29 INFO - 'talos.zip': {'path': '', 05:16:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:16:29 INFO - Running post-action listener: _resource_record_post_action 05:16:29 INFO - [mozharness: 2017-04-20 12:16:29.847368Z] Finished populate-webroot step (success) 05:16:29 INFO - [mozharness: 2017-04-20 12:16:29.847490Z] Running create-virtualenv step. 05:16:29 INFO - Running pre-action listener: _resource_record_pre_action 05:16:29 INFO - Running main action method: create_virtualenv 05:16:29 INFO - Creating virtualenv /builds/slave/test/build/venv 05:16:29 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:16:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:16:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:16:29 INFO - Using real prefix '/tools/python27' 05:16:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:16:30 INFO - Installing distribute.............................................................................................................................................................................................done. 05:16:33 INFO - Installing pip.................done. 05:16:33 INFO - Return code: 0 05:16:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:16:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:16:33 INFO - Reading from file tmpfile_stdout 05:16:33 INFO - Output received: 05:16:33 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:16:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:16:33 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:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:33 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:16:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:33 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 0x1028efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c94d7c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026cdd30>, '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 0x1025f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026c2be8>, '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.wfl2YvDoRY/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.9hTqI3oYf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:16:33 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:16:33 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:16:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfl2YvDoRY/Render', 05:16:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:33 INFO - 'HOME': '/Users/cltbld', 05:16:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:33 INFO - 'LOGNAME': 'cltbld', 05:16:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:33 INFO - 'MOZ_NO_REMOTE': '1', 05:16:33 INFO - 'NO_EM_RESTART': '1', 05:16:33 INFO - 'PAGER': '/bin/cat', 05:16:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:33 INFO - 'PWD': '/builds/slave/test', 05:16:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:33 INFO - 'SHELL': '/bin/bash', 05:16:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hTqI3oYf7/Listeners', 05:16:33 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 05:16:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:33 INFO - 'USER': 'cltbld', 05:16:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:33 INFO - 'XPC_FLAGS': '0x0', 05:16:33 INFO - 'XPC_SERVICE_NAME': '0', 05:16:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:34 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:16:34 INFO - Cleaning up... 05:16:34 INFO - Return code: 0 05:16:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:16: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:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16: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:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16: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', '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 0x1028efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c94d7c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026cdd30>, '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 0x1025f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026c2be8>, '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.wfl2YvDoRY/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.9hTqI3oYf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:16: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:16: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 psutil>=3.1.1 05:16:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfl2YvDoRY/Render', 05:16:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:34 INFO - 'HOME': '/Users/cltbld', 05:16:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:34 INFO - 'LOGNAME': 'cltbld', 05:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:34 INFO - 'MOZ_NO_REMOTE': '1', 05:16:34 INFO - 'NO_EM_RESTART': '1', 05:16:34 INFO - 'PAGER': '/bin/cat', 05:16:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:34 INFO - 'PWD': '/builds/slave/test', 05:16:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:34 INFO - 'SHELL': '/bin/bash', 05:16:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hTqI3oYf7/Listeners', 05:16:34 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 05:16:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:34 INFO - 'USER': 'cltbld', 05:16:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:34 INFO - 'XPC_FLAGS': '0x0', 05:16:34 INFO - 'XPC_SERVICE_NAME': '0', 05:16:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:34 INFO - Downloading/unpacking psutil>=3.1.1 05:16: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:16: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:16: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:16: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:16: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:16: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:16:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:16:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:16:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:16:37 INFO - Installing collected packages: psutil 05:16:37 INFO - Running setup.py install for psutil 05:16:38 INFO - building 'psutil._psutil_osx' extension 05:16:38 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:16:38 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:16:38 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:16:38 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:16:38 INFO - building 'psutil._psutil_posix' extension 05:16:38 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:16:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:16:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:16:38 INFO - ^ 05:16:38 INFO - 1 warning generated. 05:16:38 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:16:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:16:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:16:39 INFO - Successfully installed psutil 05:16:39 INFO - Cleaning up... 05:16:39 INFO - Return code: 0 05:16:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:16: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:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16: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:16:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1028efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c94d7c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026cdd30>, '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 0x1025f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026c2be8>, '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.wfl2YvDoRY/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.9hTqI3oYf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:16:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:16:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfl2YvDoRY/Render', 05:16:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:39 INFO - 'HOME': '/Users/cltbld', 05:16:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:39 INFO - 'LOGNAME': 'cltbld', 05:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:39 INFO - 'MOZ_NO_REMOTE': '1', 05:16:39 INFO - 'NO_EM_RESTART': '1', 05:16:39 INFO - 'PAGER': '/bin/cat', 05:16:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:39 INFO - 'PWD': '/builds/slave/test', 05:16:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:39 INFO - 'SHELL': '/bin/bash', 05:16:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hTqI3oYf7/Listeners', 05:16:39 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 05:16:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:39 INFO - 'USER': 'cltbld', 05:16:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:39 INFO - 'XPC_FLAGS': '0x0', 05:16:39 INFO - 'XPC_SERVICE_NAME': '0', 05:16:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:16:40 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:16:40 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:16:40 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:16:40 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:16:40 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:16:40 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:16:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:16:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:16:43 INFO - Installing collected packages: mozsystemmonitor 05:16:43 INFO - Running setup.py install for mozsystemmonitor 05:16:43 INFO - Successfully installed mozsystemmonitor 05:16:43 INFO - Cleaning up... 05:16:43 INFO - Return code: 0 05:16:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:16:43 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:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:43 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:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:43 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 0x1028efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c94d7c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026cdd30>, '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 0x1025f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026c2be8>, '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.wfl2YvDoRY/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.9hTqI3oYf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:16:43 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:16:43 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:16:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfl2YvDoRY/Render', 05:16:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:43 INFO - 'HOME': '/Users/cltbld', 05:16:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:43 INFO - 'LOGNAME': 'cltbld', 05:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:43 INFO - 'MOZ_NO_REMOTE': '1', 05:16:43 INFO - 'NO_EM_RESTART': '1', 05:16:43 INFO - 'PAGER': '/bin/cat', 05:16:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:43 INFO - 'PWD': '/builds/slave/test', 05:16:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:43 INFO - 'SHELL': '/bin/bash', 05:16:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hTqI3oYf7/Listeners', 05:16:43 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 05:16:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:43 INFO - 'USER': 'cltbld', 05:16:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:43 INFO - 'XPC_FLAGS': '0x0', 05:16:43 INFO - 'XPC_SERVICE_NAME': '0', 05:16:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:44 INFO - Downloading/unpacking jsonschema==2.5.1 05:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:16:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:16: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:16: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:16: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:16: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:16: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:16: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:16:47 INFO - Downloading functools32-3.2.3-2.tar.gz 05:16:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:16:47 INFO - warning: no files found matching '*.txt' 05:16:47 INFO - no previously-included directories found matching 'build' 05:16:47 INFO - no previously-included directories found matching 'dist' 05:16:47 INFO - no previously-included directories found matching '.git*' 05:16:47 INFO - Installing collected packages: jsonschema, functools32 05:16:47 INFO - Running setup.py install for functools32 05:16:47 INFO - warning: no files found matching '*.txt' 05:16:47 INFO - no previously-included directories found matching 'build' 05:16:47 INFO - no previously-included directories found matching 'dist' 05:16:47 INFO - no previously-included directories found matching '.git*' 05:16:47 INFO - Successfully installed jsonschema functools32 05:16:47 INFO - Cleaning up... 05:16:47 INFO - Return code: 0 05:16:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1028efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c94d7c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026cdd30>, '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 0x1025f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026c2be8>, '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.wfl2YvDoRY/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.9hTqI3oYf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:16:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfl2YvDoRY/Render', 05:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:47 INFO - 'HOME': '/Users/cltbld', 05:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:47 INFO - 'LOGNAME': 'cltbld', 05:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:47 INFO - 'MOZ_NO_REMOTE': '1', 05:16:47 INFO - 'NO_EM_RESTART': '1', 05:16:47 INFO - 'PAGER': '/bin/cat', 05:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:47 INFO - 'PWD': '/builds/slave/test', 05:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:47 INFO - 'SHELL': '/bin/bash', 05:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hTqI3oYf7/Listeners', 05:16:47 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 05:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:47 INFO - 'USER': 'cltbld', 05:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:47 INFO - 'XPC_FLAGS': '0x0', 05:16:47 INFO - 'XPC_SERVICE_NAME': '0', 05:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:16:47 INFO - Cleaning up... 05:16:47 INFO - Return code: 0 05:16:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1028efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c94d7c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026cdd30>, '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 0x1025f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026c2be8>, '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.wfl2YvDoRY/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.9hTqI3oYf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:16:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfl2YvDoRY/Render', 05:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:47 INFO - 'HOME': '/Users/cltbld', 05:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:47 INFO - 'LOGNAME': 'cltbld', 05:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:47 INFO - 'MOZ_NO_REMOTE': '1', 05:16:47 INFO - 'NO_EM_RESTART': '1', 05:16:47 INFO - 'PAGER': '/bin/cat', 05:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:47 INFO - 'PWD': '/builds/slave/test', 05:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:47 INFO - 'SHELL': '/bin/bash', 05:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hTqI3oYf7/Listeners', 05:16:47 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 05:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:47 INFO - 'USER': 'cltbld', 05:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:47 INFO - 'XPC_FLAGS': '0x0', 05:16:47 INFO - 'XPC_SERVICE_NAME': '0', 05:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:48 INFO - Downloading/unpacking blobuploader==1.2.4 05:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:50 INFO - Downloading blobuploader-1.2.4.tar.gz 05:16:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:16:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:16:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:51 INFO - Downloading docopt-0.6.1.tar.gz 05:16:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:16:52 INFO - Installing collected packages: blobuploader, requests, docopt 05:16:52 INFO - Running setup.py install for blobuploader 05:16:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:16:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:16:52 INFO - Running setup.py install for requests 05:16:52 INFO - Running setup.py install for docopt 05:16:52 INFO - Successfully installed blobuploader requests docopt 05:16:52 INFO - Cleaning up... 05:16:53 INFO - Return code: 0 05:16:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:53 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:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:53 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:16:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:53 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 0x1028efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c94d7c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026cdd30>, '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 0x1025f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026c2be8>, '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.wfl2YvDoRY/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.9hTqI3oYf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:16:53 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:16:53 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:16:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfl2YvDoRY/Render', 05:16:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:53 INFO - 'HOME': '/Users/cltbld', 05:16:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:53 INFO - 'LOGNAME': 'cltbld', 05:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:53 INFO - 'MOZ_NO_REMOTE': '1', 05:16:53 INFO - 'NO_EM_RESTART': '1', 05:16:53 INFO - 'PAGER': '/bin/cat', 05:16:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:53 INFO - 'PWD': '/builds/slave/test', 05:16:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:53 INFO - 'SHELL': '/bin/bash', 05:16:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hTqI3oYf7/Listeners', 05:16:53 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 05:16:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:53 INFO - 'USER': 'cltbld', 05:16:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:53 INFO - 'XPC_FLAGS': '0x0', 05:16:53 INFO - 'XPC_SERVICE_NAME': '0', 05:16:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:16:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-SYLWwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:16:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-zuOU6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:16:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-f1Pm8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:16:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Fus5Hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:16:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-TmHiak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:16:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-8vH6Yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:16:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-kWva3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:16:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:16:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-QwNix2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:16:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:16:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-J__8Nh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:16:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:16:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-c6Gp1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:16:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:16:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-M3m4Le-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:16:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:16:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-EQJnsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:16:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:16:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-08IL0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:16:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:16:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-MVqWxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:16:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-1Q6Wmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:16:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-g4EIjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:16:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:16:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-QfnCnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:16:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:16:55 INFO - Running setup.py install for manifestparser 05:16:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:16:55 INFO - Running setup.py install for mozcrash 05:16:55 INFO - Running setup.py install for mozdebug 05:16:55 INFO - Running setup.py install for mozdevice 05:16:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:16:55 INFO - Running setup.py install for mozfile 05:16:55 INFO - Running setup.py install for mozhttpd 05:16:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:16:55 INFO - Running setup.py install for mozinfo 05:16:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:16:56 INFO - Running setup.py install for mozInstall 05:16:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:16:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:16:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:16:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:16:56 INFO - Running setup.py install for mozleak 05:16:56 INFO - Running setup.py install for mozlog 05:16:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:16:56 INFO - Running setup.py install for moznetwork 05:16:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:16:56 INFO - Running setup.py install for mozprocess 05:16:56 INFO - Running setup.py install for mozprofile 05:16:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:16:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:16:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:16:56 INFO - Running setup.py install for mozrunner 05:16:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:16:57 INFO - Running setup.py install for mozscreenshot 05:16:57 INFO - Running setup.py install for moztest 05:16:57 INFO - Running setup.py install for mozversion 05:16:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:16:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:16:57 INFO - Cleaning up... 05:16:57 INFO - Return code: 0 05:16:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:57 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 0x1028efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c94d7c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026cdd30>, '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 0x1025f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026c2be8>, '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.wfl2YvDoRY/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.9hTqI3oYf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:16:57 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:16:57 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:16:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfl2YvDoRY/Render', 05:16:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:57 INFO - 'HOME': '/Users/cltbld', 05:16:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:57 INFO - 'LOGNAME': 'cltbld', 05:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:57 INFO - 'MOZ_NO_REMOTE': '1', 05:16:57 INFO - 'NO_EM_RESTART': '1', 05:16:57 INFO - 'PAGER': '/bin/cat', 05:16:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:57 INFO - 'PWD': '/builds/slave/test', 05:16:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:57 INFO - 'SHELL': '/bin/bash', 05:16:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hTqI3oYf7/Listeners', 05:16:57 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 05:16:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:57 INFO - 'USER': 'cltbld', 05:16:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:57 INFO - 'XPC_FLAGS': '0x0', 05:16:57 INFO - 'XPC_SERVICE_NAME': '0', 05:16:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:16:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-_G1k_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:16:57 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:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:16:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-ZKdNAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:16:58 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:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:16:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-0Xwsyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:16:58 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:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:16:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Aefa4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:16:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-fg_PAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:16:58 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:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:16:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-rJlOhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:16:58 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:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:16:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-tM7lU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:16:58 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:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:16:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-xFGOV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:16:58 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:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:16:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-KIgl7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:16:58 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:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:16:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-qXMDGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:16:58 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:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:16:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-tjHkhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:16:59 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:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:16:59 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-kzfv5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:16:59 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:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:16:59 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-9KCxm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:16:59 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:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:16:59 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-JIoxLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:16:59 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:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:59 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-HNWfn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:59 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:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:16:59 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-fykWw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:16:59 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:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:16:59 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-m93wbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:16:59 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:16:59 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:16:59 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:16:59 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:16:59 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:16:59 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:16:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:02 INFO - Downloading blessings-1.6.tar.gz 05:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:17:02 INFO - Installing collected packages: blessings 05:17:02 INFO - Running setup.py install for blessings 05:17:02 INFO - Successfully installed blessings 05:17:02 INFO - Cleaning up... 05:17:02 INFO - Return code: 0 05:17:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:17:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:17:02 INFO - Reading from file tmpfile_stdout 05:17:02 INFO - Current package versions: 05:17:02 INFO - blessings == 1.6 05:17:02 INFO - blobuploader == 1.2.4 05:17:02 INFO - docopt == 0.6.1 05:17:02 INFO - functools32 == 3.2.3-2 05:17:02 INFO - jsonschema == 2.5.1 05:17:02 INFO - manifestparser == 1.1 05:17:02 INFO - mozInstall == 1.12 05:17:02 INFO - mozcrash == 1.0 05:17:02 INFO - mozdebug == 0.1 05:17:02 INFO - mozdevice == 0.50 05:17:02 INFO - mozfile == 1.2 05:17:02 INFO - mozhttpd == 0.7 05:17:02 INFO - mozinfo == 0.9 05:17:02 INFO - mozleak == 0.1 05:17:02 INFO - mozlog == 3.4 05:17:02 INFO - moznetwork == 0.27 05:17:02 INFO - mozprocess == 0.25 05:17:02 INFO - mozprofile == 0.28 05:17:02 INFO - mozrunner == 6.13 05:17:02 INFO - mozscreenshot == 0.1 05:17:02 INFO - mozsystemmonitor == 0.3 05:17:02 INFO - moztest == 0.8 05:17:02 INFO - mozversion == 1.4 05:17:02 INFO - psutil == 3.1.1 05:17:02 INFO - requests == 1.2.3 05:17:02 INFO - wsgiref == 0.1.2 05:17:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:02 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 0x1028efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c94d7c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026cdd30>, '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 0x1025f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026c2be8>, '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.wfl2YvDoRY/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.9hTqI3oYf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:17:02 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:17:02 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:17:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfl2YvDoRY/Render', 05:17:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:02 INFO - 'HOME': '/Users/cltbld', 05:17:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:02 INFO - 'LOGNAME': 'cltbld', 05:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:02 INFO - 'MOZ_NO_REMOTE': '1', 05:17:02 INFO - 'NO_EM_RESTART': '1', 05:17:02 INFO - 'PAGER': '/bin/cat', 05:17:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:02 INFO - 'PWD': '/builds/slave/test', 05:17:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:02 INFO - 'SHELL': '/bin/bash', 05:17:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hTqI3oYf7/Listeners', 05:17:02 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 05:17:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:02 INFO - 'USER': 'cltbld', 05:17:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:02 INFO - 'XPC_FLAGS': '0x0', 05:17:02 INFO - 'XPC_SERVICE_NAME': '0', 05:17:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 INFO - Cleaning up... 05:17:03 INFO - Return code: 0 05:17:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:17:03 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:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:03 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:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:03 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 0x1028efb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8c94d7c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026cdd30>, '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 0x1025f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026c2be8>, '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.wfl2YvDoRY/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.9hTqI3oYf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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:17:03 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:17:03 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:17:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfl2YvDoRY/Render', 05:17:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:03 INFO - 'HOME': '/Users/cltbld', 05:17:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:03 INFO - 'LOGNAME': 'cltbld', 05:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:03 INFO - 'MOZ_NO_REMOTE': '1', 05:17:03 INFO - 'NO_EM_RESTART': '1', 05:17:03 INFO - 'PAGER': '/bin/cat', 05:17:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:03 INFO - 'PWD': '/builds/slave/test', 05:17:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:03 INFO - 'SHELL': '/bin/bash', 05:17:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hTqI3oYf7/Listeners', 05:17:03 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 05:17:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:03 INFO - 'USER': 'cltbld', 05:17:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:03 INFO - 'XPC_FLAGS': '0x0', 05:17:03 INFO - 'XPC_SERVICE_NAME': '0', 05:17:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:17:03 INFO - Cleaning up... 05:17:03 INFO - Return code: 0 05:17:03 INFO - Running post-action listener: _resource_record_post_action 05:17:03 INFO - Running post-action listener: _start_resource_monitoring 05:17:03 INFO - Starting resource monitoring. 05:17:03 INFO - [mozharness: 2017-04-20 12:17:03.412447Z] Finished create-virtualenv step (success) 05:17:03 INFO - [mozharness: 2017-04-20 12:17:03.413426Z] Running install step. 05:17:03 INFO - Running pre-action listener: _resource_record_pre_action 05:17:03 INFO - Running main action method: install 05:17:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:17:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:17:03 INFO - Reading from file tmpfile_stdout 05:17:03 INFO - Detecting whether we're running mozinstall >=1.0... 05:17:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:17:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:17:03 INFO - Reading from file tmpfile_stdout 05:17:03 INFO - Output received: 05:17:03 INFO - Usage: mozinstall [options] installer 05:17:03 INFO - Options: 05:17:03 INFO - -h, --help show this help message and exit 05:17:03 INFO - -d DEST, --destination=DEST 05:17:03 INFO - Directory to install application into. [default: 05:17:03 INFO - "/builds/slave/test"] 05:17:03 INFO - --app=APP Application being installed. [default: firefox] 05:17:03 INFO - mkdir: /builds/slave/test/build/application 05:17:03 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:17:03 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:17:23 INFO - Reading from file tmpfile_stdout 05:17:23 INFO - Output received: 05:17:23 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:17:23 INFO - Running post-action listener: _resource_record_post_action 05:17:23 INFO - [mozharness: 2017-04-20 12:17:23.271101Z] Finished install step (success) 05:17:23 INFO - [mozharness: 2017-04-20 12:17:23.271252Z] Running run-tests step. 05:17:23 INFO - Running pre-action listener: _resource_record_pre_action 05:17:23 INFO - Running main action method: run_tests 05:17:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:17:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:17:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:17:23 INFO - 2017-04-20 05:17:23.285 screenresolution[1628:7475] starting screenresolution argv=screenresolution get 05:17:23 INFO - 2017-04-20 05:17:23.288 screenresolution[1628:7475] Display 0: 1600x1200x32@60 05:17:23 INFO - 2017-04-20 05:17:23.294 screenresolution[1629:7478] starting screenresolution argv=screenresolution list 05:17:23 INFO - Available Modes on Display 0 05:17:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:17:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:17:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:17:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:17:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:17:23 INFO - Intel Iris: 05:17:23 INFO - Chipset Model: Intel Iris 05:17:23 INFO - Type: GPU 05:17:23 INFO - Bus: Built-In 05:17:23 INFO - VRAM (Dynamic, Max): 1536 MB 05:17:23 INFO - Vendor: Intel (0x8086) 05:17:23 INFO - Device ID: 0x0a2e 05:17:23 INFO - Revision ID: 0x0009 05:17:23 INFO - Displays: 05:17:23 INFO - Display: 05:17:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:17:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:17:23 INFO - Display Serial Number: 1600x1200 60 05:17:23 INFO - Main Display: Yes 05:17:23 INFO - Mirror: Off 05:17:23 INFO - Online: Yes 05:17:23 INFO - Rotation: Supported 05:17:23 INFO - Return code: 0 05:17:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:17:23 INFO - Python 2.7.3 05:17:23 INFO - Return code: 0 05:17:23 INFO - grabbing minidump binary from tooltool 05:17:23 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:17:23 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 0x1026cdd30>, '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 0x1025f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026c2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:17:23 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:17:23 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:17:23 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:17:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:17:23 INFO - Return code: 0 05:17:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:17:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:17:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:17:23 INFO - ENV: RUST_BACKTRACE is now 1 05:17:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:17:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:17:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--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:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0188 --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:17:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfl2YvDoRY/Render', 05:17:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:23 INFO - 'HOME': '/Users/cltbld', 05:17:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:23 INFO - 'LOGNAME': 'cltbld', 05:17:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:17:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:17:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:23 INFO - 'MOZ_NO_REMOTE': '1', 05:17:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:17:23 INFO - 'NO_EM_RESTART': '1', 05:17:23 INFO - 'PAGER': '/bin/cat', 05:17:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:23 INFO - 'PWD': '/builds/slave/test', 05:17:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:17:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:23 INFO - 'RUST_BACKTRACE': '1', 05:17:23 INFO - 'SHELL': '/bin/bash', 05:17:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hTqI3oYf7/Listeners', 05:17:23 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 05:17:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:23 INFO - 'USER': 'cltbld', 05:17:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:23 INFO - 'XPC_FLAGS': '0x0', 05:17:23 INFO - 'XPC_SERVICE_NAME': '0', 05:17:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--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:17:23 INFO - mozversion application_buildid: 20170420030346 05:17:23 INFO - mozversion application_changeset: 27311156637f9b5d4504373967e01c4241902ae7 05:17:23 INFO - mozversion application_display_name: Nightly 05:17:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:17:23 INFO - mozversion application_name: Firefox 05:17:23 INFO - mozversion application_remotingname: firefox 05:17:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:17:23 INFO - mozversion application_vendor: Mozilla 05:17:23 INFO - mozversion application_version: 55.0a1 05:17:23 INFO - mozversion platform_buildid: 20170420030346 05:17:23 INFO - mozversion platform_changeset: 27311156637f9b5d4504373967e01c4241902ae7 05:17:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:17:23 INFO - mozversion platform_version: 55.0a1 05:17:23 INFO - using testdate: 1492690643 05:17:23 INFO - actual date: 1492690643 05:17:23 INFO - starting webserver on 'localhost:49242' 05:17:23 INFO - SUITE-START | Running 2 tests 05:17:23 INFO - TEST-START | dromaeo_css 05:17:23 INFO - operating with platform_type : mac_ 05:17:23 INFO - Initialising browser for dromaeo_css test... 05:17:23 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpG0AgYR/profile http://localhost:49242/getInfo.html) 05:17:26 INFO - PID 1639 | 2017-04-20 05:17:26.807 plugin-container[1642:7673] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b4f, name = 'com.apple.tsm.portname' 05:17:26 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:17:26 INFO - PID 1639 | 2017-04-20 05:17:26.808 plugin-container[1642:7673] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 05:17:26 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:17:26 INFO - PID 1639 | 2017-04-20 05:17:26.808 plugin-container[1642:7673] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:17:26 INFO - PID 1639 | __metrics Screen width/height:1600/1200 05:17:26 INFO - PID 1639 | colorDepth:24 05:17:26 INFO - PID 1639 | Browser inner width/height: 1024/689 05:17:26 INFO - PID 1639 | __metrics 05:17:27 ERROR - PID 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3363: TypeError: this.tabs is undefined 05:17:27 INFO - TEST-INFO | 1639: exit 0 05:17:27 INFO - Browser initialized. 05:17:27 INFO - Running cycle 1/1 for dromaeo_css test... 05:17:27 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpG0AgYR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:17:30 INFO - PID 1643 | 2017-04-20 05:17:30.273 plugin-container[1644:7820] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 05:17:30 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:17:30 INFO - PID 1643 | 2017-04-20 05:17:30.274 plugin-container[1644:7820] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 05:17:30 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:17:30 INFO - PID 1643 | 2017-04-20 05:17:30.274 plugin-container[1644:7820] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:17:40 INFO - PID 1643 | RSS: Main: 236048384 05:17:40 INFO - PID 1643 | 05:20:43 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 05:20:45 INFO - PID 1643 | RSS: Main: 252051456 05:20:45 INFO - PID 1643 | 05:22:28 INFO - PID 1643 | ************************* 05:22:28 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 05:22:28 INFO - PID 1643 | 05:22:28 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:22:28 INFO - PID 1643 | Full stack: 05:22:28 INFO - PID 1643 | ************************* 05:22:28 INFO - PID 1643 | ************************* 05:22:28 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 05:22:28 INFO - PID 1643 | 05:22:28 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:22:28 INFO - PID 1643 | Full stack: 05:22:28 INFO - PID 1643 | ************************* 05:22:28 INFO - PID 1643 | ************************* 05:22:28 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 05:22:28 INFO - PID 1643 | 05:22:28 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:22:28 INFO - PID 1643 | Full stack: 05:22:28 INFO - PID 1643 | ************************* 05:22:28 INFO - PID 1643 | ************************* 05:22:28 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 05:22:28 INFO - PID 1643 | 05:22:28 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:22:28 INFO - PID 1643 | Full stack: 05:22:28 INFO - PID 1643 | ************************* 05:23:47 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 05:23:47 INFO - PID 1643 | RSS: Main: 257073152 05:23:47 INFO - PID 1643 | 05:26:49 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 05:26:50 INFO - PID 1643 | RSS: Main: 266059776 05:26:50 INFO - PID 1643 | 05:29:51 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 05:29:52 INFO - PID 1643 | RSS: Main: 258940928 05:29:52 INFO - PID 1643 | 05:32:55 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 05:32:55 INFO - PID 1643 | RSS: Main: 267640832 05:32:55 INFO - PID 1643 | 05:35:57 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 05:35:57 INFO - PID 1643 | RSS: Main: 262262784 05:35:57 INFO - PID 1643 | 05:35:57 INFO - PID 1643 | __start_tp_report 05:35:57 INFO - PID 1643 | _x_x_mozilla_page_load 05:35:57 INFO - PID 1643 | _x_x_mozilla_page_load_details 05:35:57 INFO - PID 1643 | |i|pagename|runs| 05:35:57 INFO - PID 1643 | |0;dojo.html;3546.46802979553;3596.2807438512286;3616.656417640323;3634.309481198573;3641.7450778445505;4712.293156026598;6058.333583305833;6099.878002439954;6122.234720659918;6198.628082315058;4838.564529192381;6163.167972323735;6168.4448399644025;6193.0710393441;6267.655278959655;5386.515213630772;5387.515123638872;5392.164214546751;5405.054115529786;5504.311961004874;5322.148456247001;5425.294711687495;5480.314960629921;5481.671099734029;5565.30433695788;5321.2284218788445;5399.433059528771;5405.702686352249;5446.2919820423485;5447.992121457523;5402.540784033362;5416.91874621881;5424.403315635277;5425.321834770653;5456.8090116846115;5351.09031464652;5364.812231571916;5366.195070739382;5366.865828354284;5390.460953904578;5975.462208401222;6000.609960352563;6014.6391216527145;6086.908696369558;6113.32753397126;6464.418202361763;6561.704723287416;6622.9006564901565;6640.701168447383;6652.434543063845;5621.522170615503;6736.932630673679;6740.52816302859;6746.797596072126;6776.69504872282;6197.318294987548;6251.093591429263;6276.121343011982;6294.496440284766;6326.114343991845;5722.4277572242445;6578.3092775258865;6678.6326752028135;6810.353016463428;6871.4502839772695;6324.810255692337;6363.5545511813725;6395.42441180296;6441.1304473895425;6557;5664.858378540487;6652.0354548590185;6679.398854103154;6695.497837662195;6796.490263230277;6577.980413035987;6587;6591.274959754423;6600.405963463215;6625.436837868835;6283.465905398092;6404.263509696445;6560.458292301305;6594.901076483857;6616.27221005689;5293.258943747878;5293.814716484904;5306.177542480922;5311.415744268127;5313.468653134733;4638.489766125724;4648.86053418398;4667.253239481666;4676.649251306166;4706.882327941828;5966.49284810796;6121.938780612226;6125.021800333839;6195.132681424604;6252.562320637603;6655.234648015444;6669.233038200572;6716.227633822075;6716.294789047178;6716.731330746813;6229.781957631461;6272.749090036347;6287.1198032275515;6288.434040936338;6298.716557754912;5653.491185793216;5717.828465146053;5808.506276966421;5811.476967072899;5822.359540450547;6649.667516624149;6811.080504132077;6826.044353790573;6843.726250949906;6880.243173251039;6664.700088496126;6667.599944003376;6689.331066893272;6713.362230588086;6713.496487763339;6761.830954226184;6842.7605033823575;6870.106886104774;6901.378875901193;6932.965335173292;6326.810195694136;6441.259255185713;6496.285408605144;6504.577202481823;6571.507136964842;6730.091437655854;6732.225794033749;6733.663316834237;6741.393274605309;6764.93235067643;6249.468795152463;6297.118403423433;6423.518236132402;6505.642189679424;6521.217453905561;6524.412802847766;6525.3800888915475;6532.9346706534225;6540.705668245036;6543.836404089936;5662.924044431709;5691.943080569307;6036.426539478743;6137.41694539018;6266.216722909637;6363.713632886624;6389.393007664265;6450.096986421999;6468.256150542559;6485.481161507162;6355.968220158869;6366.013267943476;6370.29926708071;6377.8724425512155;6379.425851673186 05:35:57 INFO - PID 1643 | |1;ext.html;798.4929569723225;820.5363969357313;1220.5178954313046;1264.222503160556;1265.607661624897;36972.63027369726;37474.99999999996;37478;37578.81210593943;37580.436293455554;37476.625233747654;37835.81082094586;37866.62133378672;37887;37948.81025594875;7944.165862584447;8385.413021088527;8571.914280857205;8591.785205369853;8608.225259726623;8540.487570745745;8564.528950907697;8591.914080859175;8631.223189912907;8641.913580864206;8555.10171432;8571.699990500334;8645.827083458327;8650.43772154811;8654.177853103945;8512.319014478826;8550.187732165497;8558.914410855874;8599.742007739747;8602.655893764242;8320.459170153921;8378.790530236793;8438.704645337446;8443.197896199852;8443.620037098346;36065.63934360675;36241.45637815435;36251.81874090613;36268.81865590655;36370.27259454822;3531.0289670340862;3567.1973805893726;3567.7680950738118;3576.141725985771;3580.4987301777774;29544;29862.10413687593;30171.849140754377;30292;30325.393492130035;3151.5430262611794;3184.188032051812;3185.5858738364095;3189.3302406494663;3191.808491490518;293745;294107;295758;295829;296600;38828.41757373641;39496;46982.29526557105;47386.5261347389;47933.52066479291;49045;49625.75187124041;49710.50289497131;49715.254271185964;49876.00247995093;13043.934780326228;13057;13081.542146025035;13159.53941612039;13188.670283242804;10618.681439556825;10652;10664.146868250522;10749.570017199381;10789.946050269855;13216.801747973788;13222.735545289233;13241.404136813866;13288.402021909038;13312.66873312661;5882.8822523720355;5910.556708246899;5914.467697907123;5938.465538101592;5950.256217972754;1034.3948789957958;1051.3481641382239;1058.666520046183;1080.8594882665204;1087.1683162380784;23554.411139721644;24688.382790430383;24890.5021899561;24908.875455622605;25014.249572512854;1130.0959232613875;1137.3573930729112;1166.7199872030822;1177.2289150606366;1177.8704342522315;12263.325517096646;12794.232346059267;12806.61580152597;12829.93585032069;12854.100212985002;266076;285398;303079;304444;305738;42794.57205427906;53612.391628251215;53758.46241537535;53793;53907.19139212915;1298.6104168749525;1501.3169008101288;1526.9618259543379;1542.6683263098487;1545.9768103478457;21847;24445.75554244506;24498.51002979895;24514;24536.75463245345;38237.04407389726;38275.80862095783;38295.42556861705;38315.2336953265;38329.61670383372;275204;275619;276150;276208;276225;29391.85304073466;33835.64657413676;33932;34469;34524;44098.559014409446;44201;44719;44881.55118448774;44897.775511123546;27563.72436275692;29563.556546651387;30383.544246835874;31105;31264;24555.140570079962;29949.101526954226;30822.69177308198;31093.533596996516;31304.686953131088;28133;37075.629243708296;37271.6272837279;37319.81340093282;37345.4398184022;29713.25716857096;30281.788728451498;30735.38529229358;31463.067116610422;31570.526442102928;20013.8992355416;23772.762272377055;24312.87843560771;24776;24924.62613060842 05:35:57 INFO - PID 1643 | |2;jquery.html;426.9380939763734;439.12175648702595;439.9208142534346;455.9019810740691;466.59406316504624;19816.40550783477;21348.679769803464;21851;22072.88963555184;22165.778342216574;21662.783372166272;22199;22660.660090098663;22794.2022029229;23118.612883226975;6180.938190618103;6236.719347629356;6397.680115994205;6446.548741588091;6452.4192822645955;6254.687265636723;6370.299267080617;6416.069669897885;6430.742770289183;6431.549791514596;6014;6244.406781355764;6299.811005669814;6373.8406539836415;6378.681065946707;6239.968800156015;6253.53098517613;6316.968415157918;6323.083152942798;6328.145700330466;5489.835304940818;5605.215269862222;5634.0703783875615;5670.801521946753;5677.971610141923;27243.318917027234;27703.722962770316;27876.884924602993;27901.581476277872;27998.160055198376;2236.865788052722;2278.5556816420803;2299.402155439581;2303.1017903017837;2304.043821813945;18000.819991800177;18035.909820450946;18160.545986350447;18193.818061819344;18613.44159675212;2138.860974036683;2203.305958623029;2205.382492901974;2211.7898799614013;2215.4682876109778;114616.42691786488;135931;146183.53816461805;146491;147972.2601386997;12200.633980980585;12718.745625087631;12736.936315318364;12744.107912446225;12793.424295906707;16090.034597924163;16395.950506186728;16398.590035249214;16411;16417.83582164187;6842.965785171142;7842.333401660809;8152.4293299469655;8260;8307.875381869277;7422.628868556659;7435.1821299656995;7464.701411943462;7509.774706758806;7563.7352692655495;6743.2919543448215;7956.840863182704;8041.075276343296;8088.191180881982;8120.269175774209;2233.642815960565;2237.65316375962;2242.192810588187;2250.1786847800763;2407.6388541718884;874.6486678082377;874.7244617945327;966.7486453521994;969.4764826993486;972.5963725054165;20223.4944126398;21161.576768464853;21446.57106857854;21686.132554697637;21704.56590868174;860.978475538104;1000.5147503460815;1010.5402042070987;1012.4380968562372;1013.7465633591602;8654.610542525475;10605.681829545127;11298.096152307795;11318.660440186808;11371;483865.4521093118;486191;487472;508358;511169;91927;120735.18897216373;129009.35495322464;132945.00582491272;133061;1184.5439505790332;1219.2928101700993;1229.9200551964093;1235.308227392663;1240.6898275431142;17737.556561086076;19247.51881202981;21193.78806211918;23014.42463938415;23376.415589609715;54528.18207726967;55836;55990.16014759863;56308.718456407456;56402;72377.27622723916;72462.63768681123;72728;73856.26143738494;74140.8518272364;8906.643734250558;9158.633654654006;9315.10956747713;13161.670958225737;13460;14235.857641423867;14431.85568144305;14711.632209194855;15129.773053404428;15176;11029.227954043461;12018.939905300418;12074.879251207376;12410.689732756753;12487.188332758342;10728.302660327054;11143.665690029313;11392;11720.355380453815;11797.469113889723;12289;12369.257844529357;12629.74740505203;12662.240265584094;12802.615921522369;13500.5274815381;13531.120477168952;13676.179429234278;13690.452381904612;13742.381592828147;10455.68632941013;11644.486216791764;11891.881081189078;12203.877961220274;12539.24764514132 05:35:57 INFO - PID 1643 | |3;mootools.html;1386.1752257406843;1423.900326977112;1438.0437009388756;1447.4282658349953;1461.4738694070134;11295.322280663157;11719.355435451047;11754.000909922666;11788.17482776206;11971.40142992849;11707.239029463079;11729;11888.643340699793;11891.810818918104;11908.106891983092;2498.850068995866;2538.961915571268;2556.399246177147;2558.667373241476;2561.193224134401;2287.867505584738;2465.1372019793107;2479.8636075015957;2506.1980166346775;2523.179966510882;2295.4031951692596;2480.7147178074574;2495.6755621769144;2526.68416447944;2530.380056886056;2456.4187789772036;2461.212412028152;2480.8635525046116;2491.8504889706587;2504.1485894795856;2400.195934361991;2488.850668959868;2500.5124000819847;2508.786753125968;2511.5855883779154;11779.941100294443;11886.643400697993;11887.46506407205;11888.821667674993;11890.88109118915;6638.73445062197;6885.965570172117;6904.240533541307;6907.378335949788;6917.169939607229;4108.815103320358;4387;4433.445819272591;4470.508244093147;4474.149911516802;1249.787536118862;1252.7807633664072;1298.3573131299972;1300.4798080767673;1309.0378571749757;16085.195740212943;16403.261853216634;16556.7516487254;16609;16718.99999999988;5532.114861622202;5782.392848750905;5822.214001109796;5942.078977758454;5964.31410387811;12165.695857603454;12324.075694322783;12331.568395106127;12343.444544995496;12361.93819030917;5819.53443724501;6377.075324078076;6606.636634985122;6636.767713130017;6654.733810647616;5633.521150702154;5777.740001699934;6090.634561926298;6259.843503912348;6298.464630506458;4333.761643109659;4400.867973960786;4427.645788336926;4439.023414848728;4469.865904022885;1238.6407941696807;1255.9372031398393;1305.288617703354;1320.458611969088;1341.2086868747485;688.8874467734572;703.3002162848;792.1603100713262;810.7365106340463;826.7643721539332;5787.39232380594;6785.457163426914;6831.726730930806;6980;7079.752208672672;926;933.4492649336923;934.5794392523255;941.1341565759518;981.190517822799;3737.2151848112203;4221.282381995068;4350.71720338177;4380.518143004267;4436.245838207513;12938.870611293767;13855.68371004753;14936;15059.02116362433;15064.945453818127;14745.336459859333;14944;15315.468453154595;15324.693506130037;15338.693226135638;7526.473146879775;8436.53599052058;8622.267107295826;8635.352348573882;8650.567471626393;6346.3019067903415;6915.792526224222;6979.40675042627;7131.215566287808;7151.2133665297815;12232.143749937419;12484.937575312066;12610.621681349574;12700.36498175069;12738.745225095632;12754.298513581842;12850.55023074188;12911;13014.15407998477;13087.083904126619;9312.068793120634;9504.334696571173;9526.571304291185;9597.328187026842;9709.80580388374;10800.783984320427;10895.07391871668;10905;10908.727281818017;10932.453377331261;8881.511516866634;9075.13786190311;9142.94856091558;9169.495677737788;9226.446413215228;8793.428427152214;8799.736007919773;8865.68970086044;8888.53339199025;9031.774205644648;7370.710125728084;7715.768526944201;8140.75577732669;8220.136885627162;8269.338452923632;6017.909731354121;6033.5173186145885;6044.24446944132;6216.906746398718;6228.56400051992;5232.843014709565;6018.668973206516;6090.512758979359;6151.200343955257;6259.780907668392 05:35:57 INFO - PID 1643 | |4;prototype.html;386.5696337838433;396.20165366785915;414.1920516943541;421.1072526244362;423.4452866744565;5746.442832939339;5867.735951882165;6007.249093863267;6126.86652969201;6259.155014073104;5517.668939863611;5594.412586678402;5881.441263080012;6074.422929821671;6095.7256923438445;4095.692823038269;4591.897944493323;4686.859394218179;4697.336077859258;4699.365585645945;3884.9028774280728;4029.1538776856896;4579.610733087691;4585.679002469828;4713.946006434481;4360.149770794696;4608.400907881971;4609.57103297977;4630.189716799547;4631.374764406813;4625.768711564425;4627.9768601156775;4643.257078867398;4654.301854721785;4659.784748523508;3561.786292822413;4031.09300407406;4092.7953602319767;4116.855910043164;4125.876223713293;5860.355360910296;5926.199963004982;5950.928832810093;5957.731902064417;5966.134910437962;4858.659893807435;4918.188498897677;5041.571466425358;5305.018571564273;5549.278593782782;5195.844124676266;6254.749050189935;6311.589746666498;6597.1093902323055;6602.40578347951;4735.834245801415;4743.620510359163;4760.785764640599;4761.642876784221;4761.833335833229;6099.634021958696;6684.83287917801;7004.229534751174;7018.192907815615;7019.402820956278;4408.294672852328;4649.511801260886;4812.783424745895;4884.853454396374;4897.7795999180125;4346.195953748566;4984.177610694195;4999.925001124986;5001.374828146481;5012.897162624216;5281.709505977208;5475.67145971243;5602.13166959122;5602.271704678447;5623.044082505984;5419.647722897999;5850.244926522027;5993.5237562213815;6514.5114116441455;6641.369069938348;1425.565202613213;1612.290592139455;1663.7088509510788;1695.364238410596;1708;1102.9521954143595;1148.9482973266222;1154.4227886056972;1157.890000449956;1170.9063274937985;6114.694265286718;6132.877342453126;6150.7539698411565;6158.969205153946;6180.443760061616;6199.721012554446;6209.161763162006;6685.832854178587;6725.798226053226;6756.189257289058;4755;5476.397596264408;5671;6043.516518678495;6057.121717351048;3445.4142795724288;3524.5901639344465;3524.823758812049;3526.8589256429455;3528;6086.69566521681;6108.236470441195;6443.903341449882;6702.664866756741;6965.895511567331;6284.497240220771;6800.557963732342;6833.111695479556;6917.515773895779;6931.811366771341;5849.473547380756;6676.165479315183;6940.548864323803;6949.513534052568;6962.025316455701;5664.915026274527;6392.0411938209645;6763.797086087426;6839.350261725128;6851.972204169215;5229.947700523098;5286.762095705625;5588.692621905671;5941.37615550361;6552.737890484518;6410.61536307823;6433.324500927335;6839.247682754993;7020.964895175492;7109.82225444368;3654.4335627977707;4371.775902747236;4466.754328511963;4483.887902802456;4486.865394038184;5426.67046573592;6538.607683539003;6687.966560167168;6751.189857217166;6781.254062053072;6557.868842623217;6587.374199451045;6623.735050597922;6636.435902948304;6671.032700258338;5689.687067211343;6098.390160983866;6425.485961123006;6457.354264573505;6621.966890165519;5782.508486778503;5984.341722410444;6397.232332120176;6404.583702059351;6429.22849258092;5457.508824205841;6557.082008518906;6586.571872828251;6604.471642268511;6608.306127856506;6132.601380910227;6332.461740752087;6382.138411314413;6389.808305750835;6424.582402143846 05:35:57 INFO - PID 1643 | |5;yui.html;677.8305423644089;783.749200255918;811.7727036429802;831.1978940322588;858.8797568340433;16277.918610406947;16677.833221667795;16719.414820481285;18232.635347293046;18851.811481885175;17443.3022679093;17587.472375828747;17733.113344332764;17991.550211244725;18254.634907301846;935.1023017902821;945.5981207986613;956.5886668732443;961.5576834656067;962.3071388600197;951.6288647427509;964.9807003859919;966.7969726357474;972.9902700972971;986.1863962231153;999.1357475783432;1004.8894621591655;1010.6765834932825;1015.1422048369117;1017.8371460566311;981.1660088924393;999.8700168978023;1000.3447741729179;1001.3891526169031;1007.9244056695741;716.201435399531;729.482067731911;731.7256028989129;748.0611832150671;748.2816496163689;16409.58976025597;16522.58693532671;16535.25591348392;16548.503544893672;16550.6689866202;14043.929780351033;16525.58686032847;16635.50093497197;16700.74948875768;16737;13867;14156.362963666557;14158;14190.077644953148;14203.218822964733;3088.629364476255;3520.894373168809;3639.727020473475;3643.9635603643887;3644.9817750911343;112927.43536282267;113708;114537.85462145355;114562.99999999917;115405;10892;13330.00024998129;13357.06500544972;13400.798988015187;13411.798823017663;22563.210287639853;22722.659160112613;24462.75537244605;25018;25484;6413.518986076063;7410.072661489419;7428.220036896048;7449.925500745032;7461.104667439942;3872;3957.703172262092;3975.602439756059;3987.6211759882763;4001.099752555652;3733.906652333659;4211.494620645542;4309.439772829574;4357.063231405263;4365.410669559569;869.5695630662863;886.6852267445094;890.6348397157135;893.9016708162097;894.2443635128436;650.6746626686656;658;663.7743167323133;664.7573635622963;696.5681277607915;9884.901150988542;11100.278481898651;11772.293662380285;11790.410479475991;11915.821262680893;583.7898356591623;646.1455622363845;652.6606164794281;653.1085068881039;685.475611157465;5371.113766228606;5451.400345961941;5472;5487.122060470306;5519.862003449946;103559.23305752099;104877;106371;106439;108537.45731271451;93409.06590934003;94687;94949.52525237467;96446;96769;65955;75480.99999999891;77508.4498310042;79775.60112199518;79894.60052699699;11286;11795.764084718428;12320.630381088582;12338.321392323509;12359.752804943671;37666.68166614155;39671.00822479461;40126.1943212562;40865;42528;67938.98091528533;71303.28696712966;73359;76499;77463.2253677456;16887.831121688625;16989.49031529056;17128.97226166434;17283.913580432018;17412.564685882448;18596.25614975386;18625.348112815987;18668.626627467645;18776;18945;17135.914320428317;17474.91262543679;17545.035023073342;17639.029853358188;17639.118044098;17291.221895015005;17531;17630.82369176343;17832.821671783116;17858.5535361617;16359.67280654404;16720.498385048468;16895.66208675795;16937.74593381125;17074.829251707324;10492.213084018576;11271.7745645085;11575.594854180063;11658.067354611441;11934.582289619822;13217.933910330386;13306.534271300457;13457.932710336385;13666.316684165553;13817.378217980417 05:35:57 INFO - PID 1643 | __end_tp_report 05:35:57 INFO - PID 1643 | __start_cc_report 05:35:57 INFO - PID 1643 | _x_x_mozilla_cycle_collect,411 05:35:57 INFO - PID 1643 | __end_cc_report 05:35:57 INFO - PID 1643 | __startTimestamp1492691757883__endTimestamp 05:35:57 INFO - PID 1643 | 05:35:57 INFO - PID 1643 | ------- Summary: start ------- 05:35:57 INFO - PID 1643 | Number of tests: 6 05:35:57 INFO - PID 1643 | 05:35:57 INFO - PID 1643 | [#0] dojo.html Cycles:165 Average:6075.31 Median:6294.50 stddev:717.85 (11.4%) stddev-sans-first:692.10 05:35:57 INFO - PID 1643 | Values: 3546.5 3596.3 3616.7 3634.3 3641.7 4712.3 6058.3 6099.9 6122.2 6198.6 4838.6 6163.2 6168.4 6193.1 6267.7 5386.5 5387.5 5392.2 5405.1 5504.3 5322.1 5425.3 5480.3 5481.7 5565.3 5321.2 5399.4 5405.7 5446.3 5448.0 5402.5 5416.9 5424.4 5425.3 5456.8 5351.1 5364.8 5366.2 5366.9 5390.5 5975.5 6000.6 6014.6 6086.9 6113.3 6464.4 6561.7 6622.9 6640.7 6652.4 5621.5 6736.9 6740.5 6746.8 6776.7 6197.3 6251.1 6276.1 6294.5 6326.1 5722.4 6578.3 6678.6 6810.4 6871.5 6324.8 6363.6 6395.4 6441.1 6557.0 5664.9 6652.0 6679.4 6695.5 6796.5 6578.0 6587.0 6591.3 6600.4 6625.4 6283.5 6404.3 6560.5 6594.9 6616.3 5293.3 5293.8 5306.2 5311.4 5313.5 4638.5 4648.9 4667.3 4676.6 4706.9 5966.5 6121.9 6125.0 6195.1 6252.6 6655.2 6669.2 6716.2 6716.3 6716.7 6229.8 6272.7 6287.1 6288.4 6298.7 5653.5 5717.8 5808.5 5811.5 5822.4 6649.7 6811.1 6826.0 6843.7 6880.2 6664.7 6667.6 6689.3 6713.4 6713.5 6761.8 6842.8 6870.1 6901.4 6933.0 6326.8 6441.3 6496.3 6504.6 6571.5 6730.1 6732.2 6733.7 6741.4 6764.9 6249.5 6297.1 6423.5 6505.6 6521.2 6524.4 6525.4 6532.9 6540.7 6543.8 5662.9 5691.9 6036.4 6137.4 6266.2 6363.7 6389.4 6450.1 6468.3 6485.5 6356.0 6366.0 6370.3 6377.9 6379.4 05:35:57 INFO - PID 1643 | 05:35:57 INFO - PID 1643 | [#1] ext.html Cycles:180 Average:43737.75 Median:17377.36 stddev:75475.61 (434.3%) stddev-sans-first:75618.48 05:35:57 INFO - PID 1643 | Values: 798.5 820.5 1220.5 1264.2 1265.6 36972.6 37475.0 37478.0 37578.8 37580.4 37476.6 37835.8 37866.6 37887.0 37948.8 7944.2 8385.4 8571.9 8591.8 8608.2 8540.5 8564.5 8591.9 8631.2 8641.9 8555.1 8571.7 8645.8 8650.4 8654.2 8512.3 8550.2 8558.9 8599.7 8602.7 8320.5 8378.8 8438.7 8443.2 8443.6 36065.6 36241.5 36251.8 36268.8 36370.3 3531.0 3567.2 3567.8 3576.1 3580.5 29544.0 29862.1 30171.8 30292.0 30325.4 3151.5 3184.2 3185.6 3189.3 3191.8 293745.0 294107.0 295758.0 295829.0 296600.0 38828.4 39496.0 46982.3 47386.5 47933.5 49045.0 49625.8 49710.5 49715.3 49876.0 13043.9 13057.0 13081.5 13159.5 13188.7 10618.7 10652.0 10664.1 10749.6 10789.9 13216.8 13222.7 13241.4 13288.4 13312.7 5882.9 5910.6 5914.5 5938.5 5950.3 1034.4 1051.3 1058.7 1080.9 1087.2 23554.4 24688.4 24890.5 24908.9 25014.2 1130.1 1137.4 1166.7 1177.2 1177.9 12263.3 12794.2 12806.6 12829.9 12854.1 266076.0 285398.0 303079.0 304444.0 305738.0 42794.6 53612.4 53758.5 53793.0 53907.2 1298.6 1501.3 1527.0 1542.7 1546.0 21847.0 24445.8 24498.5 24514.0 24536.8 38237.0 38275.8 38295.4 38315.2 38329.6 275204.0 275619.0 276150.0 276208.0 276225.0 29391.9 33835.6 33932.0 34469.0 34524.0 44098.6 44201.0 44719.0 44881.6 44897.8 27563.7 29563.6 30383.5 31105.0 31264.0 24555.1 29949.1 30822.7 31093.5 31304.7 28133.0 37075.6 37271.6 37319.8 37345.4 29713.3 30281.8 30735.4 31463.1 31570.5 20013.9 23772.8 24312.9 24776.0 24924.6 05:35:57 INFO - PID 1643 | 05:35:57 INFO - PID 1643 | [#2] jquery.html Cycles:180 Average:33527.35 Median:2237.26 stddev:84037.45 (3756.3%) stddev-sans-first:84236.44 05:35:57 INFO - PID 1643 | Values: 426.9 439.1 439.9 455.9 466.6 19816.4 21348.7 21851.0 22072.9 22165.8 21662.8 22199.0 22660.7 22794.2 23118.6 6180.9 6236.7 6397.7 6446.5 6452.4 6254.7 6370.3 6416.1 6430.7 6431.5 6014.0 6244.4 6299.8 6373.8 6378.7 6240.0 6253.5 6317.0 6323.1 6328.1 5489.8 5605.2 5634.1 5670.8 5678.0 27243.3 27703.7 27876.9 27901.6 27998.2 2236.9 2278.6 2299.4 2303.1 2304.0 18000.8 18035.9 18160.5 18193.8 18613.4 2138.9 2203.3 2205.4 2211.8 2215.5 114616.4 135931.0 146183.5 146491.0 147972.3 12200.6 12718.7 12736.9 12744.1 12793.4 16090.0 16396.0 16398.6 16411.0 16417.8 6843.0 7842.3 8152.4 8260.0 8307.9 7422.6 7435.2 7464.7 7509.8 7563.7 6743.3 7956.8 8041.1 8088.2 8120.3 2233.6 2237.7 2242.2 2250.2 2407.6 874.6 874.7 966.7 969.5 972.6 20223.5 21161.6 21446.6 21686.1 21704.6 861.0 1000.5 1010.5 1012.4 1013.7 8654.6 10605.7 11298.1 11318.7 11371.0 483865.5 486191.0 487472.0 508358.0 511169.0 91927.0 120735.2 129009.4 132945.0 133061.0 1184.5 1219.3 1229.9 1235.3 1240.7 17737.6 19247.5 21193.8 23014.4 23376.4 54528.2 55836.0 55990.2 56308.7 56402.0 72377.3 72462.6 72728.0 73856.3 74140.9 8906.6 9158.6 9315.1 13161.7 13460.0 14235.9 14431.9 14711.6 15129.8 15176.0 11029.2 12018.9 12074.9 12410.7 12487.2 10728.3 11143.7 11392.0 11720.4 11797.5 12289.0 12369.3 12629.7 12662.2 12802.6 13500.5 13531.1 13676.2 13690.5 13742.4 10455.7 11644.5 11891.9 12203.9 12539.2 05:35:57 INFO - PID 1643 | 05:35:57 INFO - PID 1643 | [#3] mootools.html Cycles:180 Average:7104.61 Median:3923.02 stddev:4444.42 (113.3%) stddev-sans-first:4436.11 05:35:57 INFO - PID 1643 | Values: 1386.2 1423.9 1438.0 1447.4 1461.5 11295.3 11719.4 11754.0 11788.2 11971.4 11707.2 11729.0 11888.6 11891.8 11908.1 2498.9 2539.0 2556.4 2558.7 2561.2 2287.9 2465.1 2479.9 2506.2 2523.2 2295.4 2480.7 2495.7 2526.7 2530.4 2456.4 2461.2 2480.9 2491.9 2504.1 2400.2 2488.9 2500.5 2508.8 2511.6 11779.9 11886.6 11887.5 11888.8 11890.9 6638.7 6886.0 6904.2 6907.4 6917.2 4108.8 4387.0 4433.4 4470.5 4474.1 1249.8 1252.8 1298.4 1300.5 1309.0 16085.2 16403.3 16556.8 16609.0 16719.0 5532.1 5782.4 5822.2 5942.1 5964.3 12165.7 12324.1 12331.6 12343.4 12361.9 5819.5 6377.1 6606.6 6636.8 6654.7 5633.5 5777.7 6090.6 6259.8 6298.5 4333.8 4400.9 4427.6 4439.0 4469.9 1238.6 1255.9 1305.3 1320.5 1341.2 688.9 703.3 792.2 810.7 826.8 5787.4 6785.5 6831.7 6980.0 7079.8 926.0 933.4 934.6 941.1 981.2 3737.2 4221.3 4350.7 4380.5 4436.2 12938.9 13855.7 14936.0 15059.0 15064.9 14745.3 14944.0 15315.5 15324.7 15338.7 7526.5 8436.5 8622.3 8635.4 8650.6 6346.3 6915.8 6979.4 7131.2 7151.2 12232.1 12484.9 12610.6 12700.4 12738.7 12754.3 12850.6 12911.0 13014.2 13087.1 9312.1 9504.3 9526.6 9597.3 9709.8 10800.8 10895.1 10905.0 10908.7 10932.5 8881.5 9075.1 9142.9 9169.5 9226.4 8793.4 8799.7 8865.7 8888.5 9031.8 7370.7 7715.8 8140.8 8220.1 8269.3 6017.9 6033.5 6044.2 6216.9 6228.6 5232.8 6018.7 6090.5 6151.2 6259.8 05:35:57 INFO - PID 1643 | 05:35:57 INFO - PID 1643 | [#4] prototype.html Cycles:180 Average:5284.56 Median:5815.99 stddev:1586.18 (27.3%) stddev-sans-first:1547.44 05:35:57 INFO - PID 1643 | Values: 386.6 396.2 414.2 421.1 423.4 5746.4 5867.7 6007.2 6126.9 6259.2 5517.7 5594.4 5881.4 6074.4 6095.7 4095.7 4591.9 4686.9 4697.3 4699.4 3884.9 4029.2 4579.6 4585.7 4713.9 4360.1 4608.4 4609.6 4630.2 4631.4 4625.8 4628.0 4643.3 4654.3 4659.8 3561.8 4031.1 4092.8 4116.9 4125.9 5860.4 5926.2 5950.9 5957.7 5966.1 4858.7 4918.2 5041.6 5305.0 5549.3 5195.8 6254.7 6311.6 6597.1 6602.4 4735.8 4743.6 4760.8 4761.6 4761.8 6099.6 6684.8 7004.2 7018.2 7019.4 4408.3 4649.5 4812.8 4884.9 4897.8 4346.2 4984.2 4999.9 5001.4 5012.9 5281.7 5475.7 5602.1 5602.3 5623.0 5419.6 5850.2 5993.5 6514.5 6641.4 1425.6 1612.3 1663.7 1695.4 1708.0 1103.0 1148.9 1154.4 1157.9 1170.9 6114.7 6132.9 6150.8 6159.0 6180.4 6199.7 6209.2 6685.8 6725.8 6756.2 4755.0 5476.4 5671.0 6043.5 6057.1 3445.4 3524.6 3524.8 3526.9 3528.0 6086.7 6108.2 6443.9 6702.7 6965.9 6284.5 6800.6 6833.1 6917.5 6931.8 5849.5 6676.2 6940.5 6949.5 6962.0 5664.9 6392.0 6763.8 6839.4 6852.0 5229.9 5286.8 5588.7 5941.4 6552.7 6410.6 6433.3 6839.2 7021.0 7109.8 3654.4 4371.8 4466.8 4483.9 4486.9 5426.7 6538.6 6688.0 6751.2 6781.3 6557.9 6587.4 6623.7 6636.4 6671.0 5689.7 6098.4 6425.5 6457.4 6622.0 5782.5 5984.3 6397.2 6404.6 6429.2 5457.5 6557.1 6586.6 6604.5 6608.3 6132.6 6332.5 6382.1 6389.8 6424.6 05:35:57 INFO - PID 1643 | 05:35:57 INFO - PID 1643 | [#5] yui.html Cycles:180 Average:21985.02 Median:20754.11 stddev:30396.70 (146.5%) stddev-sans-first:30439.87 05:35:57 INFO - PID 1643 | Values: 677.8 783.7 811.8 831.2 858.9 16277.9 16677.8 16719.4 18232.6 18851.8 17443.3 17587.5 17733.1 17991.6 18254.6 935.1 945.6 956.6 961.6 962.3 951.6 965.0 966.8 973.0 986.2 999.1 1004.9 1010.7 1015.1 1017.8 981.2 999.9 1000.3 1001.4 1007.9 716.2 729.5 731.7 748.1 748.3 16409.6 16522.6 16535.3 16548.5 16550.7 14043.9 16525.6 16635.5 16700.7 16737.0 13867.0 14156.4 14158.0 14190.1 14203.2 3088.6 3520.9 3639.7 3644.0 3645.0 112927.4 113708.0 114537.9 114563.0 115405.0 10892.0 13330.0 13357.1 13400.8 13411.8 22563.2 22722.7 24462.8 25018.0 25484.0 6413.5 7410.1 7428.2 7449.9 7461.1 3872.0 3957.7 3975.6 3987.6 4001.1 3733.9 4211.5 4309.4 4357.1 4365.4 869.6 886.7 890.6 893.9 894.2 650.7 658.0 663.8 664.8 696.6 9884.9 11100.3 11772.3 11790.4 11915.8 583.8 646.1 652.7 653.1 685.5 5371.1 5451.4 5472.0 5487.1 5519.9 103559.2 104877.0 106371.0 106439.0 108537.5 93409.1 94687.0 94949.5 96446.0 96769.0 65955.0 75481.0 77508.4 79775.6 79894.6 11286.0 11795.8 12320.6 12338.3 12359.8 37666.7 39671.0 40126.2 40865.0 42528.0 67939.0 71303.3 73359.0 76499.0 77463.2 16887.8 16989.5 17129.0 17283.9 17412.6 18596.3 18625.3 18668.6 18776.0 18945.0 17135.9 17474.9 17545.0 17639.0 17639.1 17291.2 17531.0 17630.8 17832.8 17858.6 16359.7 16720.5 16895.7 16937.7 17074.8 10492.2 11271.8 11575.6 11658.1 11934.6 13217.9 13306.5 13457.9 13666.3 13817.4 05:35:57 INFO - PID 1643 | -------- Summary: end -------- 05:35:57 INFO - PID 1643 | 05:35:58 INFO - TEST-INFO | 1643: exit 0 05:35:58 INFO - TEST-OK | dromaeo_css | took 1114650ms 05:35:58 INFO - TEST-START | kraken 05:35:58 INFO - operating with platform_type : mac_ 05:35:58 INFO - Initialising browser for kraken test... 05:35:58 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmplWk7FJ/profile http://localhost:49242/getInfo.html) 05:36:01 INFO - PID 1655 | 2017-04-20 05:36:01.389 plugin-container[1657:10227] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9043, name = 'com.apple.tsm.portname' 05:36:01 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:36:01 INFO - PID 1655 | 2017-04-20 05:36:01.390 plugin-container[1657:10227] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b13, name = 'com.apple.CFPasteboardClient' 05:36:01 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:36:01 INFO - PID 1655 | 2017-04-20 05:36:01.390 plugin-container[1657:10227] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:36:01 INFO - PID 1655 | __metrics Screen width/height:1600/1200 05:36:01 INFO - PID 1655 | colorDepth:24 05:36:01 INFO - PID 1655 | Browser inner width/height: 1024/689 05:36:01 INFO - PID 1655 | __metrics 05:36:01 ERROR - PID 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3363: TypeError: this.tabs is undefined 05:36:02 INFO - TEST-INFO | 1655: exit 0 05:36:02 INFO - Browser initialized. 05:36:02 INFO - Running cycle 1/1 for kraken test... 05:36:02 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmplWk7FJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:36:05 INFO - PID 1658 | 2017-04-20 05:36:05.050 plugin-container[1659:10355] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 05:36:05 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:36:05 INFO - PID 1658 | 2017-04-20 05:36:05.051 plugin-container[1659:10355] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444f, name = 'com.apple.CFPasteboardClient' 05:36:05 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:36:05 INFO - PID 1658 | 2017-04-20 05:36:05.051 plugin-container[1659:10355] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:36:14 INFO - PID 1658 | RSS: Main: 236457984 05:36:14 INFO - PID 1658 | 05:36:17 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 05:36:18 INFO - PID 1658 | RSS: Main: 248459264 05:36:18 INFO - PID 1658 | 05:36:21 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 05:36:21 INFO - PID 1658 | RSS: Main: 256733184 05:36:21 INFO - PID 1658 | 05:36:25 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 05:36:25 INFO - PID 1658 | RSS: Main: 259055616 05:36:25 INFO - PID 1658 | 05:36:27 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 05:36:28 INFO - PID 1658 | RSS: Main: 261373952 05:36:28 INFO - PID 1658 | 05:36:30 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:36:31 INFO - PID 1658 | RSS: Main: 261816320 05:36:31 INFO - PID 1658 | 05:36:34 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 05:36:34 INFO - PID 1658 | RSS: Main: 267554816 05:36:34 INFO - PID 1658 | 05:36:37 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 05:36:38 INFO - PID 1658 | RSS: Main: 268570624 05:36:38 INFO - PID 1658 | 05:36:41 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 05:36:42 INFO - PID 1658 | RSS: Main: 268292096 05:36:42 INFO - PID 1658 | 05:36:43 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:36:44 INFO - PID 1658 | RSS: Main: 267776000 05:36:44 INFO - PID 1658 | 05:36:45 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:36:46 INFO - PID 1658 | RSS: Main: 267878400 05:36:46 INFO - PID 1658 | 05:36:48 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:36:49 INFO - PID 1658 | RSS: Main: 267542528 05:36:49 INFO - PID 1658 | 05:36:52 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:36:53 INFO - PID 1658 | RSS: Main: 267870208 05:36:53 INFO - PID 1658 | 05:36:56 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:36:56 INFO - PID 1658 | RSS: Main: 267968512 05:36:56 INFO - PID 1658 | 05:36:58 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 05:36:58 INFO - PID 1658 | RSS: Main: 269721600 05:36:58 INFO - PID 1658 | 05:36:58 INFO - PID 1658 | __start_tp_report 05:36:58 INFO - PID 1658 | _x_x_mozilla_page_load 05:36:58 INFO - PID 1658 | _x_x_mozilla_page_load_details 05:36:58 INFO - PID 1658 | |i|pagename|runs| 05:36:58 INFO - PID 1658 | |0;ai-astar;105;117;114;115;114;115;115;114;116;115 05:36:58 INFO - PID 1658 | |1;audio-beat-detection;143;112;110;150;116;122;95;128;163;144 05:36:58 INFO - PID 1658 | |2;audio-dft;199;205;200;250;197;230;271;280;218;197 05:36:58 INFO - PID 1658 | |3;audio-fft;87;49;57;95;85;85;88;88;78;59 05:36:58 INFO - PID 1658 | |4;audio-oscillator;97;86;87;72;72;97;76;71;95;115 05:36:58 INFO - PID 1658 | |5;imaging-gaussian-blur;109;89;89;88;106;89;88;89;88;90 05:36:58 INFO - PID 1658 | |6;imaging-darkroom;99;97;104;155;131;99;96;96;96;107 05:36:58 INFO - PID 1658 | |7;imaging-desaturate;91;93;101;91;100;91;90;91;93;117 05:36:58 INFO - PID 1658 | |8;json-parse-financial;49;49;49;51;67;79;78;73;50;52 05:36:58 INFO - PID 1658 | |9;json-stringify-tinderbox;60;55;57;59;65;59;57;56;58;57 05:36:58 INFO - PID 1658 | |10;stanford-crypto-aes;83;85;83;83;86;83;83;87;84;83 05:36:59 INFO - PID 1658 | |11;stanford-crypto-ccm;155;134;169;123;146;161;158;155;185;142 05:36:59 INFO - PID 1658 | |12;stanford-crypto-pbkdf2;190;188;186;186;186;183;198;247;232;187 05:36:59 INFO - PID 1658 | |13;stanford-crypto-sha256-iterative;72;86;103;97;75;68;70;68;72;72 05:36:59 INFO - PID 1658 | __end_tp_report 05:36:59 INFO - PID 1658 | __start_cc_report 05:36:59 INFO - PID 1658 | _x_x_mozilla_cycle_collect,1001 05:36:59 INFO - PID 1658 | __end_cc_report 05:36:59 INFO - PID 1658 | __startTimestamp1492691818987__endTimestamp 05:36:59 INFO - PID 1658 | 05:36:59 INFO - PID 1658 | ------- Summary: start ------- 05:36:59 INFO - PID 1658 | Number of tests: 14 05:36:59 INFO - PID 1658 | 05:36:59 INFO - PID 1658 | [#0] ai-astar Cycles:10 Average:114.00 Median:115.00 stddev:3.30 (2.9%) stddev-sans-first:1.00 05:36:59 INFO - PID 1658 | Values: 105.0 117.0 114.0 115.0 114.0 115.0 115.0 114.0 116.0 115.0 05:36:59 INFO - PID 1658 | 05:36:59 INFO - PID 1658 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:143.50 stddev:21.18 (14.8%) stddev-sans-first:21.79 05:36:59 INFO - PID 1658 | Values: 143.0 112.0 110.0 150.0 116.0 122.0 95.0 128.0 163.0 144.0 05:36:59 INFO - PID 1658 | 05:36:59 INFO - PID 1658 | [#2] audio-dft Cycles:10 Average:224.70 Median:224.00 stddev:31.81 (14.2%) stddev-sans-first:32.35 05:36:59 INFO - PID 1658 | Values: 199.0 205.0 200.0 250.0 197.0 230.0 271.0 280.0 218.0 197.0 05:36:59 INFO - PID 1658 | 05:36:59 INFO - PID 1658 | [#3] audio-fft Cycles:10 Average:77.10 Median:86.00 stddev:16.00 (18.6%) stddev-sans-first:16.56 05:36:59 INFO - PID 1658 | Values: 87.0 49.0 57.0 95.0 85.0 85.0 88.0 88.0 78.0 59.0 05:36:59 INFO - PID 1658 | 05:36:59 INFO - PID 1658 | [#4] audio-oscillator Cycles:10 Average:86.80 Median:86.50 stddev:14.44 (16.7%) stddev-sans-first:14.83 05:36:59 INFO - PID 1658 | Values: 97.0 86.0 87.0 72.0 72.0 97.0 76.0 71.0 95.0 115.0 05:36:59 INFO - PID 1658 | 05:36:59 INFO - PID 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:92.50 Median:89.00 stddev:7.96 (8.9%) stddev-sans-first:5.79 05:36:59 INFO - PID 1658 | Values: 109.0 89.0 89.0 88.0 106.0 89.0 88.0 89.0 88.0 90.0 05:36:59 INFO - PID 1658 | 05:36:59 INFO - PID 1658 | [#6] imaging-darkroom Cycles:10 Average:108.00 Median:96.00 stddev:19.64 (20.5%) stddev-sans-first:20.55 05:36:59 INFO - PID 1658 | Values: 99.0 97.0 104.0 155.0 131.0 99.0 96.0 96.0 96.0 107.0 05:36:59 INFO - PID 1658 | 05:36:59 INFO - PID 1658 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:91.00 stddev:8.40 (9.2%) stddev-sans-first:8.73 05:36:59 INFO - PID 1658 | Values: 91.0 93.0 101.0 91.0 100.0 91.0 90.0 91.0 93.0 117.0 05:36:59 INFO - PID 1658 | 05:36:59 INFO - PID 1658 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:59.50 stddev:12.95 (21.8%) stddev-sans-first:13.15 05:36:59 INFO - PID 1658 | Values: 49.0 49.0 49.0 51.0 67.0 79.0 78.0 73.0 50.0 52.0 05:36:59 INFO - PID 1658 | 05:36:59 INFO - PID 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:58.30 Median:58.50 stddev:2.79 (4.8%) stddev-sans-first:2.89 05:36:59 INFO - PID 1658 | Values: 60.0 55.0 57.0 59.0 65.0 59.0 57.0 56.0 58.0 57.0 05:36:59 INFO - PID 1658 | 05:36:59 INFO - PID 1658 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:83.50 stddev:1.49 (1.8%) stddev-sans-first:1.54 05:36:59 INFO - PID 1658 | Values: 83.0 85.0 83.0 83.0 86.0 83.0 83.0 87.0 84.0 83.0 05:36:59 INFO - PID 1658 | 05:36:59 INFO - PID 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:152.80 Median:156.50 stddev:17.66 (11.3%) stddev-sans-first:18.72 05:36:59 INFO - PID 1658 | Values: 155.0 134.0 169.0 123.0 146.0 161.0 158.0 155.0 185.0 142.0 05:36:59 INFO - PID 1658 | 05:36:59 INFO - PID 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.30 Median:189.00 stddev:22.36 (11.8%) stddev-sans-first:23.51 05:36:59 INFO - PID 1658 | Values: 190.0 188.0 186.0 186.0 186.0 183.0 198.0 247.0 232.0 187.0 05:36:59 INFO - PID 1658 | 05:36:59 INFO - PID 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.30 Median:72.00 stddev:12.61 (17.5%) stddev-sans-first:13.16 05:36:59 INFO - PID 1658 | Values: 72.0 86.0 103.0 97.0 75.0 68.0 70.0 68.0 72.0 72.0 05:36:59 INFO - PID 1658 | -------- Summary: end -------- 05:36:59 INFO - PID 1658 | 05:36:59 INFO - TEST-INFO | 1658: exit 0 05:37:00 INFO - TEST-OK | kraken | took 61519ms 05:37:00 INFO - SUITE-END | took 1176s 05:37:00 INFO - Completed test suite (00:19:36) 05:37:00 INFO - javascript benchmark 05:37:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7886.522153490662, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3546.46802979553, 3596.2807438512286, 3616.656417640323, 3634.309481198573, 3641.7450778445505, 4712.293156026598, 6058.333583305833, 6099.878002439954, 6122.234720659918, 6198.628082315058, 4838.564529192381, 6163.167972323735, 6168.4448399644025, 6193.0710393441, 6267.655278959655, 5386.515213630772, 5387.515123638872, 5392.164214546751, 5405.054115529786, 5504.311961004874, 5322.148456247001, 5425.294711687495, 5480.314960629921, 5481.671099734029, 5565.30433695788, 5321.2284218788445, 5399.433059528771, 5405.702686352249, 5446.2919820423485, 5447.992121457523, 5402.540784033362, 5416.91874621881, 5424.403315635277, 5425.321834770653, 5456.8090116846115, 5351.09031464652, 5364.812231571916, 5366.195070739382, 5366.865828354284, 5390.460953904578, 5975.462208401222, 6000.609960352563, 6014.6391216527145, 6086.908696369558, 6113.32753397126, 6464.418202361763, 6561.704723287416, 6622.9006564901565, 6640.701168447383, 6652.434543063845, 5621.522170615503, 6736.932630673679, 6740.52816302859, 6746.797596072126, 6776.69504872282, 6197.318294987548, 6251.093591429263, 6276.121343011982, 6294.496440284766, 6326.114343991845, 5722.4277572242445, 6578.3092775258865, 6678.6326752028135, 6810.353016463428, 6871.4502839772695, 6324.810255692337, 6363.5545511813725, 6395.42441180296, 6441.1304473895425, 6557.0, 5664.858378540487, 6652.0354548590185, 6679.398854103154, 6695.497837662195, 6796.490263230277, 6577.980413035987, 6587.0, 6591.274959754423, 6600.405963463215, 6625.436837868835, 6283.465905398092, 6404.263509696445, 6560.458292301305, 6594.901076483857, 6616.27221005689, 5293.258943747878, 5293.814716484904, 5306.177542480922, 5311.415744268127, 5313.468653134733, 4638.489766125724, 4648.86053418398, 4667.253239481666, 4676.649251306166, 4706.882327941828, 5966.49284810796, 6121.938780612226, 6125.021800333839, 6195.132681424604, 6252.562320637603, 6655.234648015444, 6669.233038200572, 6716.227633822075, 6716.294789047178, 6716.731330746813, 6229.781957631461, 6272.749090036347, 6287.1198032275515, 6288.434040936338, 6298.716557754912, 5653.491185793216, 5717.828465146053, 5808.506276966421, 5811.476967072899, 5822.359540450547, 6649.667516624149, 6811.080504132077, 6826.044353790573, 6843.726250949906, 6880.243173251039, 6664.700088496126, 6667.599944003376, 6689.331066893272, 6713.362230588086, 6713.496487763339, 6761.830954226184, 6842.7605033823575, 6870.106886104774, 6901.378875901193, 6932.965335173292, 6326.810195694136, 6441.259255185713, 6496.285408605144, 6504.577202481823, 6571.507136964842, 6730.091437655854, 6732.225794033749, 6733.663316834237, 6741.393274605309, 6764.93235067643, 6249.468795152463, 6297.118403423433, 6423.518236132402, 6505.642189679424, 6521.217453905561, 6524.412802847766, 6525.3800888915475, 6532.9346706534225, 6540.705668245036, 6543.836404089936, 5662.924044431709, 5691.943080569307, 6036.426539478743, 6137.41694539018, 6266.216722909637, 6363.713632886624, 6389.393007664265, 6450.096986421999, 6468.256150542559, 6485.481161507162, 6355.968220158869, 6366.013267943476, 6370.29926708071, 6377.8724425512155, 6379.425851673186], "value": 6029.777186013413, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [798.4929569723225, 820.5363969357313, 1220.5178954313046, 1264.222503160556, 1265.607661624897, 36972.63027369726, 37474.99999999996, 37478.0, 37578.81210593943, 37580.436293455554, 37476.625233747654, 37835.81082094586, 37866.62133378672, 37887.0, 37948.81025594875, 7944.165862584447, 8385.413021088527, 8571.914280857205, 8591.785205369853, 8608.225259726623, 8540.487570745745, 8564.528950907697, 8591.914080859175, 8631.223189912907, 8641.913580864206, 8555.10171432, 8571.699990500334, 8645.827083458327, 8650.43772154811, 8654.177853103945, 8512.319014478826, 8550.187732165497, 8558.914410855874, 8599.742007739747, 8602.655893764242, 8320.459170153921, 8378.790530236793, 8438.704645337446, 8443.197896199852, 8443.620037098346, 36065.63934360675, 36241.45637815435, 36251.81874090613, 36268.81865590655, 36370.27259454822, 3531.0289670340862, 3567.1973805893726, 3567.7680950738118, 3576.141725985771, 3580.4987301777774, 29544.0, 29862.10413687593, 30171.849140754377, 30292.0, 30325.393492130035, 3151.5430262611794, 3184.188032051812, 3185.5858738364095, 3189.3302406494663, 3191.808491490518, 293745.0, 294107.0, 295758.0, 295829.0, 296600.0, 38828.41757373641, 39496.0, 46982.29526557105, 47386.5261347389, 47933.52066479291, 49045.0, 49625.75187124041, 49710.50289497131, 49715.254271185964, 49876.00247995093, 13043.934780326228, 13057.0, 13081.542146025035, 13159.53941612039, 13188.670283242804, 10618.681439556825, 10652.0, 10664.146868250522, 10749.570017199381, 10789.946050269855, 13216.801747973788, 13222.735545289233, 13241.404136813866, 13288.402021909038, 13312.66873312661, 5882.8822523720355, 5910.556708246899, 5914.467697907123, 5938.465538101592, 5950.256217972754, 1034.3948789957958, 1051.3481641382239, 1058.666520046183, 1080.8594882665204, 1087.1683162380784, 23554.411139721644, 24688.382790430383, 24890.5021899561, 24908.875455622605, 25014.249572512854, 1130.0959232613875, 1137.3573930729112, 1166.7199872030822, 1177.2289150606366, 1177.8704342522315, 12263.325517096646, 12794.232346059267, 12806.61580152597, 12829.93585032069, 12854.100212985002, 266076.0, 285398.0, 303079.0, 304444.0, 305738.0, 42794.57205427906, 53612.391628251215, 53758.46241537535, 53793.0, 53907.19139212915, 1298.6104168749525, 1501.3169008101288, 1526.9618259543379, 1542.6683263098487, 1545.9768103478457, 21847.0, 24445.75554244506, 24498.51002979895, 24514.0, 24536.75463245345, 38237.04407389726, 38275.80862095783, 38295.42556861705, 38315.2336953265, 38329.61670383372, 275204.0, 275619.0, 276150.0, 276208.0, 276225.0, 29391.85304073466, 33835.64657413676, 33932.0, 34469.0, 34524.0, 44098.559014409446, 44201.0, 44719.0, 44881.55118448774, 44897.775511123546, 27563.72436275692, 29563.556546651387, 30383.544246835874, 31105.0, 31264.0, 24555.140570079962, 29949.101526954226, 30822.69177308198, 31093.533596996516, 31304.686953131088, 28133.0, 37075.629243708296, 37271.6272837279, 37319.81340093282, 37345.4398184022, 29713.25716857096, 30281.788728451498, 30735.38529229358, 31463.067116610422, 31570.526442102928, 20013.8992355416, 23772.762272377055, 24312.87843560771, 24776.0, 24924.62613060842], "value": 17382.83086609915, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.9380939763734, 439.12175648702595, 439.9208142534346, 455.9019810740691, 466.59406316504624, 19816.40550783477, 21348.679769803464, 21851.0, 22072.88963555184, 22165.778342216574, 21662.783372166272, 22199.0, 22660.660090098663, 22794.2022029229, 23118.612883226975, 6180.938190618103, 6236.719347629356, 6397.680115994205, 6446.548741588091, 6452.4192822645955, 6254.687265636723, 6370.299267080617, 6416.069669897885, 6430.742770289183, 6431.549791514596, 6014.0, 6244.406781355764, 6299.811005669814, 6373.8406539836415, 6378.681065946707, 6239.968800156015, 6253.53098517613, 6316.968415157918, 6323.083152942798, 6328.145700330466, 5489.835304940818, 5605.215269862222, 5634.0703783875615, 5670.801521946753, 5677.971610141923, 27243.318917027234, 27703.722962770316, 27876.884924602993, 27901.581476277872, 27998.160055198376, 2236.865788052722, 2278.5556816420803, 2299.402155439581, 2303.1017903017837, 2304.043821813945, 18000.819991800177, 18035.909820450946, 18160.545986350447, 18193.818061819344, 18613.44159675212, 2138.860974036683, 2203.305958623029, 2205.382492901974, 2211.7898799614013, 2215.4682876109778, 114616.42691786488, 135931.0, 146183.53816461805, 146491.0, 147972.2601386997, 12200.633980980585, 12718.745625087631, 12736.936315318364, 12744.107912446225, 12793.424295906707, 16090.034597924163, 16395.950506186728, 16398.590035249214, 16411.0, 16417.83582164187, 6842.965785171142, 7842.333401660809, 8152.4293299469655, 8260.0, 8307.875381869277, 7422.628868556659, 7435.1821299656995, 7464.701411943462, 7509.774706758806, 7563.7352692655495, 6743.2919543448215, 7956.840863182704, 8041.075276343296, 8088.191180881982, 8120.269175774209, 2233.642815960565, 2237.65316375962, 2242.192810588187, 2250.1786847800763, 2407.6388541718884, 874.6486678082377, 874.7244617945327, 966.7486453521994, 969.4764826993486, 972.5963725054165, 20223.4944126398, 21161.576768464853, 21446.57106857854, 21686.132554697637, 21704.56590868174, 860.978475538104, 1000.5147503460815, 1010.5402042070987, 1012.4380968562372, 1013.7465633591602, 8654.610542525475, 10605.681829545127, 11298.096152307795, 11318.660440186808, 11371.0, 483865.4521093118, 486191.0, 487472.0, 508358.0, 511169.0, 91927.0, 120735.18897216373, 129009.35495322464, 132945.00582491272, 133061.0, 1184.5439505790332, 1219.2928101700993, 1229.9200551964093, 1235.308227392663, 1240.6898275431142, 17737.556561086076, 19247.51881202981, 21193.78806211918, 23014.42463938415, 23376.415589609715, 54528.18207726967, 55836.0, 55990.16014759863, 56308.718456407456, 56402.0, 72377.27622723916, 72462.63768681123, 72728.0, 73856.26143738494, 74140.8518272364, 8906.643734250558, 9158.633654654006, 9315.10956747713, 13161.670958225737, 13460.0, 14235.857641423867, 14431.85568144305, 14711.632209194855, 15129.773053404428, 15176.0, 11029.227954043461, 12018.939905300418, 12074.879251207376, 12410.689732756753, 12487.188332758342, 10728.302660327054, 11143.665690029313, 11392.0, 11720.355380453815, 11797.469113889723, 12289.0, 12369.257844529357, 12629.74740505203, 12662.240265584094, 12802.615921522369, 13500.5274815381, 13531.120477168952, 13676.179429234278, 13690.452381904612, 13742.381592828147, 10455.68632941013, 11644.486216791764, 11891.881081189078, 12203.877961220274, 12539.24764514132], "value": 10604.084093413312, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.1752257406843, 1423.900326977112, 1438.0437009388756, 1447.4282658349953, 1461.4738694070134, 11295.322280663157, 11719.355435451047, 11754.000909922666, 11788.17482776206, 11971.40142992849, 11707.239029463079, 11729.0, 11888.643340699793, 11891.810818918104, 11908.106891983092, 2498.850068995866, 2538.961915571268, 2556.399246177147, 2558.667373241476, 2561.193224134401, 2287.867505584738, 2465.1372019793107, 2479.8636075015957, 2506.1980166346775, 2523.179966510882, 2295.4031951692596, 2480.7147178074574, 2495.6755621769144, 2526.68416447944, 2530.380056886056, 2456.4187789772036, 2461.212412028152, 2480.8635525046116, 2491.8504889706587, 2504.1485894795856, 2400.195934361991, 2488.850668959868, 2500.5124000819847, 2508.786753125968, 2511.5855883779154, 11779.941100294443, 11886.643400697993, 11887.46506407205, 11888.821667674993, 11890.88109118915, 6638.73445062197, 6885.965570172117, 6904.240533541307, 6907.378335949788, 6917.169939607229, 4108.815103320358, 4387.0, 4433.445819272591, 4470.508244093147, 4474.149911516802, 1249.787536118862, 1252.7807633664072, 1298.3573131299972, 1300.4798080767673, 1309.0378571749757, 16085.195740212943, 16403.261853216634, 16556.7516487254, 16609.0, 16718.99999999988, 5532.114861622202, 5782.392848750905, 5822.214001109796, 5942.078977758454, 5964.31410387811, 12165.695857603454, 12324.075694322783, 12331.568395106127, 12343.444544995496, 12361.93819030917, 5819.53443724501, 6377.075324078076, 6606.636634985122, 6636.767713130017, 6654.733810647616, 5633.521150702154, 5777.740001699934, 6090.634561926298, 6259.843503912348, 6298.464630506458, 4333.761643109659, 4400.867973960786, 4427.645788336926, 4439.023414848728, 4469.865904022885, 1238.6407941696807, 1255.9372031398393, 1305.288617703354, 1320.458611969088, 1341.2086868747485, 688.8874467734572, 703.3002162848, 792.1603100713262, 810.7365106340463, 826.7643721539332, 5787.39232380594, 6785.457163426914, 6831.726730930806, 6980.0, 7079.752208672672, 926.0, 933.4492649336923, 934.5794392523255, 941.1341565759518, 981.190517822799, 3737.2151848112203, 4221.282381995068, 4350.71720338177, 4380.518143004267, 4436.245838207513, 12938.870611293767, 13855.68371004753, 14936.0, 15059.02116362433, 15064.945453818127, 14745.336459859333, 14944.0, 15315.468453154595, 15324.693506130037, 15338.693226135638, 7526.473146879775, 8436.53599052058, 8622.267107295826, 8635.352348573882, 8650.567471626393, 6346.3019067903415, 6915.792526224222, 6979.40675042627, 7131.215566287808, 7151.2133665297815, 12232.143749937419, 12484.937575312066, 12610.621681349574, 12700.36498175069, 12738.745225095632, 12754.298513581842, 12850.55023074188, 12911.0, 13014.15407998477, 13087.083904126619, 9312.068793120634, 9504.334696571173, 9526.571304291185, 9597.328187026842, 9709.80580388374, 10800.783984320427, 10895.07391871668, 10905.0, 10908.727281818017, 10932.453377331261, 8881.511516866634, 9075.13786190311, 9142.94856091558, 9169.495677737788, 9226.446413215228, 8793.428427152214, 8799.736007919773, 8865.68970086044, 8888.53339199025, 9031.774205644648, 7370.710125728084, 7715.768526944201, 8140.75577732669, 8220.136885627162, 8269.338452923632, 6017.909731354121, 6033.5173186145885, 6044.24446944132, 6216.906746398718, 6228.56400051992, 5232.843014709565, 6018.668973206516, 6090.512758979359, 6151.200343955257, 6259.780907668392], "value": 5394.326030259343, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.5696337838433, 396.20165366785915, 414.1920516943541, 421.1072526244362, 423.4452866744565, 5746.442832939339, 5867.735951882165, 6007.249093863267, 6126.86652969201, 6259.155014073104, 5517.668939863611, 5594.412586678402, 5881.441263080012, 6074.422929821671, 6095.7256923438445, 4095.692823038269, 4591.897944493323, 4686.859394218179, 4697.336077859258, 4699.365585645945, 3884.9028774280728, 4029.1538776856896, 4579.610733087691, 4585.679002469828, 4713.946006434481, 4360.149770794696, 4608.400907881971, 4609.57103297977, 4630.189716799547, 4631.374764406813, 4625.768711564425, 4627.9768601156775, 4643.257078867398, 4654.301854721785, 4659.784748523508, 3561.786292822413, 4031.09300407406, 4092.7953602319767, 4116.855910043164, 4125.876223713293, 5860.355360910296, 5926.199963004982, 5950.928832810093, 5957.731902064417, 5966.134910437962, 4858.659893807435, 4918.188498897677, 5041.571466425358, 5305.018571564273, 5549.278593782782, 5195.844124676266, 6254.749050189935, 6311.589746666498, 6597.1093902323055, 6602.40578347951, 4735.834245801415, 4743.620510359163, 4760.785764640599, 4761.642876784221, 4761.833335833229, 6099.634021958696, 6684.83287917801, 7004.229534751174, 7018.192907815615, 7019.402820956278, 4408.294672852328, 4649.511801260886, 4812.783424745895, 4884.853454396374, 4897.7795999180125, 4346.195953748566, 4984.177610694195, 4999.925001124986, 5001.374828146481, 5012.897162624216, 5281.709505977208, 5475.67145971243, 5602.13166959122, 5602.271704678447, 5623.044082505984, 5419.647722897999, 5850.244926522027, 5993.5237562213815, 6514.5114116441455, 6641.369069938348, 1425.565202613213, 1612.290592139455, 1663.7088509510788, 1695.364238410596, 1708.0, 1102.9521954143595, 1148.9482973266222, 1154.4227886056972, 1157.890000449956, 1170.9063274937985, 6114.694265286718, 6132.877342453126, 6150.7539698411565, 6158.969205153946, 6180.443760061616, 6199.721012554446, 6209.161763162006, 6685.832854178587, 6725.798226053226, 6756.189257289058, 4755.0, 5476.397596264408, 5671.0, 6043.516518678495, 6057.121717351048, 3445.4142795724288, 3524.5901639344465, 3524.823758812049, 3526.8589256429455, 3528.0, 6086.69566521681, 6108.236470441195, 6443.903341449882, 6702.664866756741, 6965.895511567331, 6284.497240220771, 6800.557963732342, 6833.111695479556, 6917.515773895779, 6931.811366771341, 5849.473547380756, 6676.165479315183, 6940.548864323803, 6949.513534052568, 6962.025316455701, 5664.915026274527, 6392.0411938209645, 6763.797086087426, 6839.350261725128, 6851.972204169215, 5229.947700523098, 5286.762095705625, 5588.692621905671, 5941.37615550361, 6552.737890484518, 6410.61536307823, 6433.324500927335, 6839.247682754993, 7020.964895175492, 7109.82225444368, 3654.4335627977707, 4371.775902747236, 4466.754328511963, 4483.887902802456, 4486.865394038184, 5426.67046573592, 6538.607683539003, 6687.966560167168, 6751.189857217166, 6781.254062053072, 6557.868842623217, 6587.374199451045, 6623.735050597922, 6636.435902948304, 6671.032700258338, 5689.687067211343, 6098.390160983866, 6425.485961123006, 6457.354264573505, 6621.966890165519, 5782.508486778503, 5984.341722410444, 6397.232332120176, 6404.583702059351, 6429.22849258092, 5457.508824205841, 6557.082008518906, 6586.571872828251, 6604.471642268511, 6608.306127856506, 6132.601380910227, 6332.461740752087, 6382.138411314413, 6389.808305750835, 6424.582402143846], "value": 4804.217631542281, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.8305423644089, 783.749200255918, 811.7727036429802, 831.1978940322588, 858.8797568340433, 16277.918610406947, 16677.833221667795, 16719.414820481285, 18232.635347293046, 18851.811481885175, 17443.3022679093, 17587.472375828747, 17733.113344332764, 17991.550211244725, 18254.634907301846, 935.1023017902821, 945.5981207986613, 956.5886668732443, 961.5576834656067, 962.3071388600197, 951.6288647427509, 964.9807003859919, 966.7969726357474, 972.9902700972971, 986.1863962231153, 999.1357475783432, 1004.8894621591655, 1010.6765834932825, 1015.1422048369117, 1017.8371460566311, 981.1660088924393, 999.8700168978023, 1000.3447741729179, 1001.3891526169031, 1007.9244056695741, 716.201435399531, 729.482067731911, 731.7256028989129, 748.0611832150671, 748.2816496163689, 16409.58976025597, 16522.58693532671, 16535.25591348392, 16548.503544893672, 16550.6689866202, 14043.929780351033, 16525.58686032847, 16635.50093497197, 16700.74948875768, 16737.0, 13867.0, 14156.362963666557, 14158.0, 14190.077644953148, 14203.218822964733, 3088.629364476255, 3520.894373168809, 3639.727020473475, 3643.9635603643887, 3644.9817750911343, 112927.43536282267, 113708.0, 114537.85462145355, 114562.99999999917, 115405.0, 10892.0, 13330.00024998129, 13357.06500544972, 13400.798988015187, 13411.798823017663, 22563.210287639853, 22722.659160112613, 24462.75537244605, 25018.0, 25484.0, 6413.518986076063, 7410.072661489419, 7428.220036896048, 7449.925500745032, 7461.104667439942, 3872.0, 3957.703172262092, 3975.602439756059, 3987.6211759882763, 4001.099752555652, 3733.906652333659, 4211.494620645542, 4309.439772829574, 4357.063231405263, 4365.410669559569, 869.5695630662863, 886.6852267445094, 890.6348397157135, 893.9016708162097, 894.2443635128436, 650.6746626686656, 658.0, 663.7743167323133, 664.7573635622963, 696.5681277607915, 9884.901150988542, 11100.278481898651, 11772.293662380285, 11790.410479475991, 11915.821262680893, 583.7898356591623, 646.1455622363845, 652.6606164794281, 653.1085068881039, 685.475611157465, 5371.113766228606, 5451.400345961941, 5472.0, 5487.122060470306, 5519.862003449946, 103559.23305752099, 104877.0, 106371.0, 106439.0, 108537.45731271451, 93409.06590934003, 94687.0, 94949.52525237467, 96446.0, 96769.0, 65955.0, 75480.99999999891, 77508.4498310042, 79775.60112199518, 79894.60052699699, 11286.0, 11795.764084718428, 12320.630381088582, 12338.321392323509, 12359.752804943671, 37666.68166614155, 39671.00822479461, 40126.1943212562, 40865.0, 42528.0, 67938.98091528533, 71303.28696712966, 73359.0, 76499.0, 77463.2253677456, 16887.831121688625, 16989.49031529056, 17128.97226166434, 17283.913580432018, 17412.564685882448, 18596.25614975386, 18625.348112815987, 18668.626627467645, 18776.0, 18945.0, 17135.914320428317, 17474.91262543679, 17545.035023073342, 17639.029853358188, 17639.118044098, 17291.221895015005, 17531.0, 17630.82369176343, 17832.821671783116, 17858.5535361617, 16359.67280654404, 16720.498385048468, 16895.66208675795, 16937.74593381125, 17074.829251707324, 10492.213084018576, 11271.7745645085, 11575.594854180063, 11658.067354611441, 11934.582289619822, 13217.933910330386, 13306.534271300457, 13457.932710336385, 13666.316684165553, 13817.378217980417], "value": 8352.719895584569, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1558.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 114.0, 115.0, 114.0, 115.0, 115.0, 114.0, 116.0, 115.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 112.0, 110.0, 150.0, 116.0, 122.0, 95.0, 128.0, 163.0, 144.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 205.0, 200.0, 250.0, 197.0, 230.0, 271.0, 280.0, 218.0, 197.0], "value": 224.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 49.0, 57.0, 95.0, 85.0, 85.0, 88.0, 88.0, 78.0, 59.0], "value": 77.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 86.0, 87.0, 72.0, 72.0, 97.0, 76.0, 71.0, 95.0, 115.0], "value": 86.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 89.0, 89.0, 88.0, 106.0, 89.0, 88.0, 89.0, 88.0, 90.0], "value": 92.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 97.0, 104.0, 155.0, 131.0, 99.0, 96.0, 96.0, 96.0, 107.0], "value": 108.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 101.0, 91.0, 100.0, 91.0, 90.0, 91.0, 93.0, 117.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 49.0, 51.0, 67.0, 79.0, 78.0, 73.0, 50.0, 52.0], "value": 59.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 55.0, 57.0, 59.0, 65.0, 59.0, 57.0, 56.0, 58.0, 57.0], "value": 58.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 83.0, 83.0, 86.0, 83.0, 83.0, 87.0, 84.0, 83.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 134.0, 169.0, 123.0, 146.0, 161.0, 158.0, 155.0, 185.0, 142.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 188.0, 186.0, 186.0, 186.0, 183.0, 198.0, 247.0, 232.0, 187.0], "value": 198.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 86.0, 103.0, 97.0, 75.0, 68.0, 70.0, 68.0, 72.0, 72.0], "value": 78.3, "unit": "score"}]}]} 05:37:00 INFO - Return code: 0 05:37:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:37:00 ERROR - # TBPL SUCCESS # 05:37:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:37:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:37:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:37:00 INFO - 2017-04-20 05:37:00.337 screenresolution[1663:10734] starting screenresolution argv=screenresolution get 05:37:00 INFO - 2017-04-20 05:37:00.340 screenresolution[1663:10734] Display 0: 1600x1200x32@60 05:37:00 INFO - 2017-04-20 05:37:00.347 screenresolution[1664:10737] starting screenresolution argv=screenresolution list 05:37:00 INFO - Available Modes on Display 0 05:37:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:37:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:37:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:37:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:37:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:37:00 INFO - Intel Iris: 05:37:00 INFO - Chipset Model: Intel Iris 05:37:00 INFO - Type: GPU 05:37:00 INFO - Bus: Built-In 05:37:00 INFO - VRAM (Dynamic, Max): 1536 MB 05:37:00 INFO - Vendor: Intel (0x8086) 05:37:00 INFO - Device ID: 0x0a2e 05:37:00 INFO - Revision ID: 0x0009 05:37:00 INFO - Displays: 05:37:00 INFO - Display: 05:37:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:37:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:37:00 INFO - Display Serial Number: 1600x1200 60 05:37:00 INFO - Main Display: Yes 05:37:00 INFO - Mirror: Off 05:37:00 INFO - Online: Yes 05:37:00 INFO - Rotation: Supported 05:37:00 INFO - Return code: 0 05:37:00 INFO - Running post-action listener: _resource_record_post_action 05:37:00 INFO - [mozharness: 2017-04-20 12:37:00.596133Z] Finished run-tests step (success) 05:37:00 INFO - Running post-run listener: _resource_record_post_run 05:37:00 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 40880128; Write bytes: 513437184; Read time: 406; Write time: 1724 05:37:00 INFO - TinderboxPrint: CPU usage
54.0% 05:37:00 INFO - TinderboxPrint: I/O read bytes / time
40,880,128 / 406 05:37:00 INFO - TinderboxPrint: I/O write bytes / time
513,437,184 / 1,724 05:37:00 INFO - TinderboxPrint: CPU idle
2,199.7 (46.0%) 05:37:00 INFO - TinderboxPrint: CPU system
960.7 (20.1%) 05:37:00 INFO - TinderboxPrint: CPU user
1,625.2 (34.0%) 05:37:00 INFO - TinderboxPrint: Swap in / out
247,435,264 / 0 05:37:00 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 194517504; Write bytes: 193545728; Read time: 14830; Write time: 407 05:37:00 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 39852032; Write bytes: 303065088; Read time: 389; Write time: 1264 05:37:01 INFO - Running post-run listener: _upload_blobber_files 05:37:01 INFO - Blob upload gear active. 05:37:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:37:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:37:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:37:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:37:01 INFO - (blobuploader) - INFO - Open directory for files ... 05:37:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:37:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:37:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:02 INFO - (blobuploader) - INFO - Done attempting. 05:37:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:37:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:37:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:03 INFO - (blobuploader) - INFO - Done attempting. 05:37:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:37:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:37:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:04 INFO - (blobuploader) - INFO - Done attempting. 05:37:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:37:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:37:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:06 INFO - (blobuploader) - INFO - Done attempting. 05:37:06 INFO - (blobuploader) - INFO - Iteration through files over. 05:37:06 INFO - Return code: 0 05:37:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:37:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:37:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/032627be3dc87aa6927e2f8a62521730156c31dec9524c5ed91a13ceb55260bf70cd79f66789acbc1ca3dce1186d613a5575299109dcd54af4bedb3772346eeb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7973dc0c4333776a06a051370e5403034a917d5f296476b4685cc82494bf83c45fb545346b01869d523239aa61d1e41168248e60315045ff7f7e0895905edc43", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2adb1b6e3494f61e1d3d23ccd7706824e7a2c54acdcb1b4afb38d66e89628a8b1e22af4f4ebc0f6acf491728196337f7d9e8b55e3d7080fc9545a5270cbff025", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6265eff6ee022b13fffcd2d21398290e98bd57cdb7baaf188a31ba988d17245c329deb38f2bda183d6c0167e5185ddb06c17f9037cee1de1b1fc195cbe2d3e07"} 05:37:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:37:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:37:06 INFO - Contents: 05:37:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/032627be3dc87aa6927e2f8a62521730156c31dec9524c5ed91a13ceb55260bf70cd79f66789acbc1ca3dce1186d613a5575299109dcd54af4bedb3772346eeb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7973dc0c4333776a06a051370e5403034a917d5f296476b4685cc82494bf83c45fb545346b01869d523239aa61d1e41168248e60315045ff7f7e0895905edc43", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2adb1b6e3494f61e1d3d23ccd7706824e7a2c54acdcb1b4afb38d66e89628a8b1e22af4f4ebc0f6acf491728196337f7d9e8b55e3d7080fc9545a5270cbff025", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6265eff6ee022b13fffcd2d21398290e98bd57cdb7baaf188a31ba988d17245c329deb38f2bda183d6c0167e5185ddb06c17f9037cee1de1b1fc195cbe2d3e07"} 05:37:06 INFO - Running post-run listener: copy_logs_to_upload_dir 05:37:06 INFO - Copying logs to upload dir... 05:37:06 INFO - mkdir: /builds/slave/test/build/upload/logs 05:37:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.398085 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-20 05:37:06.791312) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-20 05:37:06.794920) ========= 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.wfl2YvDoRY/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.9hTqI3oYf7/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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/032627be3dc87aa6927e2f8a62521730156c31dec9524c5ed91a13ceb55260bf70cd79f66789acbc1ca3dce1186d613a5575299109dcd54af4bedb3772346eeb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7973dc0c4333776a06a051370e5403034a917d5f296476b4685cc82494bf83c45fb545346b01869d523239aa61d1e41168248e60315045ff7f7e0895905edc43", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2adb1b6e3494f61e1d3d23ccd7706824e7a2c54acdcb1b4afb38d66e89628a8b1e22af4f4ebc0f6acf491728196337f7d9e8b55e3d7080fc9545a5270cbff025", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6265eff6ee022b13fffcd2d21398290e98bd57cdb7baaf188a31ba988d17245c329deb38f2bda183d6c0167e5185ddb06c17f9037cee1de1b1fc195cbe2d3e07"} build_url:https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011051 build_url: 'https://queue.taskcluster.net/v1/task/UF03-rn_SGCSCi4tVBensA/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/032627be3dc87aa6927e2f8a62521730156c31dec9524c5ed91a13ceb55260bf70cd79f66789acbc1ca3dce1186d613a5575299109dcd54af4bedb3772346eeb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7973dc0c4333776a06a051370e5403034a917d5f296476b4685cc82494bf83c45fb545346b01869d523239aa61d1e41168248e60315045ff7f7e0895905edc43", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2adb1b6e3494f61e1d3d23ccd7706824e7a2c54acdcb1b4afb38d66e89628a8b1e22af4f4ebc0f6acf491728196337f7d9e8b55e3d7080fc9545a5270cbff025", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6265eff6ee022b13fffcd2d21398290e98bd57cdb7baaf188a31ba988d17245c329deb38f2bda183d6c0167e5185ddb06c17f9037cee1de1b1fc195cbe2d3e07"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-20 05:37:06.834512) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 05:37:06.834866) ========= 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.wfl2YvDoRY/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.9hTqI3oYf7/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.005176 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 05:37:06.884354) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-20 05:37:06.884681) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-20 05:37:06.885101) ========= ========= Total master_lag: 0.18 =========