builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0321 starttime: 1491035859.78 results: success (0) buildid: 20170401004003 builduid: 3b93d9103b7a41f3a33e66ef532ea687 revision: 500c5e8ec870c4dfe84effdfe1bd92f0bba5cc7f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-01 01:37:39.780808) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-01 01:37:39.781271) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-01 01:37:39.781571) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-01 01:37:39.806817) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 01:37:39.807178) ========= 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.NvfaUGUMVE/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.e1mudrhqHA/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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-01 01:37:39-- 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% 8.13M=0.001s 2017-04-01 01:37:40 (8.13 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.629172 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 01:37:40.460279) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 01:37:40.460599) ========= 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.NvfaUGUMVE/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.e1mudrhqHA/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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.021018 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 01:37:40.509024) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-01 01:37:40.509344) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 500c5e8ec870c4dfe84effdfe1bd92f0bba5cc7f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 500c5e8ec870c4dfe84effdfe1bd92f0bba5cc7f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NvfaUGUMVE/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.e1mudrhqHA/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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-01 01:37:40,599 truncating revision to first 12 chars 2017-04-01 01:37:40,599 Setting DEBUG logging. 2017-04-01 01:37:40,599 attempt 1/10 2017-04-01 01:37:40,599 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/500c5e8ec870?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-01 01:37:41,456 unpacking tar archive at: mozilla-aurora-500c5e8ec870/testing/mozharness/ program finished with exit code 0 elapsedTime=2.719714 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-01 01:37:43.255256) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-01 01:37:43.255808) ========= script_repo_revision: 500c5e8ec870c4dfe84effdfe1bd92f0bba5cc7f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-01 01:37:43.256500) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-01 01:37:43.257006) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-01 01:37:43.279804) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-01 01:37:43.280108) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NvfaUGUMVE/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.e1mudrhqHA/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 01:37:43 INFO - MultiFileLogger online at 20170401 01:37:43 in /builds/slave/test 01:37:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 01:37:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:37:43 INFO - {'append_to_log': False, 01:37:43 INFO - 'base_work_dir': '/builds/slave/test', 01:37:43 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:37:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:37:43 INFO - 'branch': 'Mozilla-Aurora', 01:37:43 INFO - 'buildbot_json_path': 'buildprops.json', 01:37:43 INFO - 'config_files': ('talos/mac_config.py',), 01:37:43 INFO - 'default_actions': ('clobber', 01:37:43 INFO - 'read-buildbot-config', 01:37:43 INFO - 'download-and-extract', 01:37:43 INFO - 'populate-webroot', 01:37:43 INFO - 'create-virtualenv', 01:37:43 INFO - 'install', 01:37:43 INFO - 'run-tests'), 01:37:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:37:43 INFO - 'download_minidump_stackwalk': True, 01:37:43 INFO - 'download_symbols': 'ondemand', 01:37:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:37:43 INFO - 'tooltool.py': '/tools/tooltool.py', 01:37:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:37:43 INFO - '/tools/misc-python/virtualenv.py')}, 01:37:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:37:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:37:43 INFO - 'gecko_profile': False, 01:37:43 INFO - 'gecko_profile_interval': 0, 01:37:43 INFO - 'installer_path': 'installer.exe', 01:37:43 INFO - 'log_level': 'info', 01:37:43 INFO - 'log_name': 'talos', 01:37:43 INFO - 'log_to_console': True, 01:37:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:37:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:37:43 INFO - 'opt_config_files': (), 01:37:43 INFO - 'pip_index': False, 01:37:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:37:43 INFO - 'cmd': ('bash', 01:37:43 INFO - '-c', 01:37:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:37:43 INFO - 'enabled': True, 01:37:43 INFO - 'halt_on_failure': False, 01:37:43 INFO - 'name': 'check_screen_resolution'},), 01:37:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:37:43 INFO - 'cmd': ('bash', 01:37:43 INFO - '-c', 01:37:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:37:43 INFO - 'enabled': True, 01:37:43 INFO - 'halt_on_failure': False, 01:37:43 INFO - 'name': 'check_screen_resolution'},), 01:37:43 INFO - 'run_cmd_checks_enabled': True, 01:37:43 INFO - 'suite': 'dromaeojs-e10s', 01:37:43 INFO - 'system_bits': '32', 01:37:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:37:43 INFO - 'title': 't-yosemite-r7-0321', 01:37:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:37:43 INFO - 'use_talos_json': True, 01:37:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:37:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:37:43 INFO - 'work_dir': 'build'} 01:37:43 INFO - [mozharness: 2017-04-01 08:37:43.500760Z] Running clobber step. 01:37:43 INFO - Running pre-action listener: _resource_record_pre_action 01:37:43 INFO - Running main action method: clobber 01:37:43 INFO - rmtree: /builds/slave/test/build 01:37:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:37:45 INFO - Running post-action listener: _resource_record_post_action 01:37:45 INFO - [mozharness: 2017-04-01 08:37:45.238857Z] Finished clobber step (success) 01:37:45 INFO - [mozharness: 2017-04-01 08:37:45.238988Z] Running read-buildbot-config step. 01:37:45 INFO - Running pre-action listener: _resource_record_pre_action 01:37:45 INFO - Running main action method: read_buildbot_config 01:37:45 INFO - Using buildbot properties: 01:37:45 INFO - { 01:37:45 INFO - "project": "", 01:37:45 INFO - "product": "firefox", 01:37:45 INFO - "script_repo_revision": "production", 01:37:45 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 01:37:45 INFO - "repository": "", 01:37:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 01:37:45 INFO - "buildid": "20170401004003", 01:37:45 INFO - "stage_platform": "macosx64", 01:37:45 INFO - "basedir": "/builds/slave/test", 01:37:45 INFO - "nightly_build": "True", 01:37:45 INFO - "buildnumber": 95, 01:37:45 INFO - "slavename": "t-yosemite-r7-0321", 01:37:45 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 01:37:45 INFO - "platform": "yosemite_r7", 01:37:45 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 01:37:45 INFO - "branch": "mozilla-aurora", 01:37:45 INFO - "repo_path": "releases/mozilla-aurora", 01:37:45 INFO - "revision": "500c5e8ec870c4dfe84effdfe1bd92f0bba5cc7f", 01:37:45 INFO - "pgo_build": "False", 01:37:45 INFO - "builduid": "3b93d9103b7a41f3a33e66ef532ea687", 01:37:45 INFO - "slavebuilddir": "test" 01:37:45 INFO - } 01:37:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 01:37:45 INFO - Running post-action listener: _resource_record_post_action 01:37:45 INFO - [mozharness: 2017-04-01 08:37:45.242250Z] Finished read-buildbot-config step (success) 01:37:45 INFO - [mozharness: 2017-04-01 08:37:45.242389Z] Running download-and-extract step. 01:37:45 INFO - Running pre-action listener: _resource_record_pre_action 01:37:45 INFO - Running main action method: download_and_extract 01:37:45 INFO - mkdir: /builds/slave/test/build/tests 01:37:45 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')]} 01:37:45 INFO - trying https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 01:37:45 INFO - Downloading https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:37:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 01:37:46 INFO - Downloaded 1199 bytes. 01:37:46 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:37:46 INFO - Using the following test package requirements: 01:37:46 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:37:46 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:37:46 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 01:37:46 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:37:46 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 01:37:46 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:37:46 INFO - u'jsshell-mac.zip'], 01:37:46 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:37:46 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 01:37:46 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:37:46 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:37:46 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 01:37:46 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:37:46 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 01:37:46 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:37:46 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 01:37:46 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:37:46 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 01:37:46 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 01:37:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:37:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:37:46 INFO - Fetch https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:37:48 INFO - Expected file size: 51789106 01:37:48 INFO - Obtained file size: 51789106 01:38:04 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 01:38:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:38:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:38:04 INFO - Fetch https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:38:05 INFO - Expected file size: 51789106 01:38:05 INFO - Obtained file size: 51789106 01:38:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 01:38:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 01:38:20 INFO - Fetch https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 01:38:21 INFO - Expected file size: 14404438 01:38:21 INFO - Obtained file size: 14404438 01:38:22 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')]} 01:38:22 INFO - trying https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:38:22 INFO - Downloading https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 01:38:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 01:38:24 INFO - Downloaded 74751529 bytes. 01:38:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:38:24 INFO - mkdir: /builds/slave/test/properties 01:38:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:38:24 INFO - Writing to file /builds/slave/test/properties/build_url 01:38:24 INFO - Contents: 01:38:24 INFO - build_url:https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:38:25 INFO - Running post-action listener: _resource_record_post_action 01:38:25 INFO - Running post-action listener: set_extra_try_arguments 01:38:25 INFO - [mozharness: 2017-04-01 08:38:25.220938Z] Finished download-and-extract step (success) 01:38:25 INFO - [mozharness: 2017-04-01 08:38:25.221098Z] Running populate-webroot step. 01:38:25 INFO - Running pre-action listener: _resource_record_pre_action 01:38:25 INFO - Running main action method: populate_webroot 01:38:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:38:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:38:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:38:25 INFO - '--tppagecycles', 01:38:25 INFO - '7'], 01:38:25 INFO - 'tests': ['tsvgm']}}, 01:38:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:38:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:38:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:38:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:38:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:38:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:38:25 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:38:25 INFO - 'talos_options': ['--disable-e10s'], 01:38:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:38:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:38:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:38:25 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:38:25 INFO - 'talos_options': ['--disable-e10s'], 01:38:25 INFO - 'tests': ['damp', 'tps']}, 01:38:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:38:25 INFO - 'tests': ['damp', 'tps']}, 01:38:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:38:25 INFO - 'tests': ['dromaeo_dom']}, 01:38:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:38:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:38:25 INFO - 'tests': ['basic_compositor_video']}, 01:38:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:38:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:38:25 INFO - 'tests': ['a11yr', 01:38:25 INFO - 'ts_paint', 01:38:25 INFO - 'tpaint', 01:38:25 INFO - 'sessionrestore', 01:38:25 INFO - 'sessionrestore_no_auto_restore', 01:38:25 INFO - 'tabpaint']}, 01:38:25 INFO - 'other-e10s': {'tests': ['a11yr', 01:38:25 INFO - 'ts_paint', 01:38:25 INFO - 'tpaint', 01:38:25 INFO - 'sessionrestore', 01:38:25 INFO - 'sessionrestore_no_auto_restore', 01:38:25 INFO - 'tabpaint']}, 01:38:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:38:25 INFO - 'ts_paint', 01:38:25 INFO - 'tpaint', 01:38:25 INFO - 'sessionrestore', 01:38:25 INFO - 'sessionrestore_no_auto_restore', 01:38:25 INFO - 'tabpaint']}, 01:38:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:38:25 INFO - 'ts_paint', 01:38:25 INFO - 'tpaint', 01:38:25 INFO - 'sessionrestore', 01:38:25 INFO - 'sessionrestore_no_auto_restore', 01:38:25 INFO - 'tabpaint']}, 01:38:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:38:25 INFO - 'tests': ['a11yr', 01:38:25 INFO - 'ts_paint', 01:38:25 INFO - 'tpaint', 01:38:25 INFO - 'sessionrestore', 01:38:25 INFO - 'sessionrestore_no_auto_restore', 01:38:25 INFO - 'tabpaint']}, 01:38:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:38:25 INFO - 'tests': ['a11yr', 01:38:25 INFO - 'ts_paint', 01:38:25 INFO - 'tpaint', 01:38:25 INFO - 'sessionrestore', 01:38:25 INFO - 'sessionrestore_no_auto_restore', 01:38:25 INFO - 'tabpaint']}, 01:38:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:38:25 INFO - 'tests': ['tsvgx', 01:38:25 INFO - 'tsvgr_opacity', 01:38:25 INFO - 'tart', 01:38:25 INFO - 'tscrollx', 01:38:25 INFO - 'cart', 01:38:25 INFO - 'tsvg_static']}, 01:38:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:38:25 INFO - 'tsvgr_opacity', 01:38:25 INFO - 'tart', 01:38:25 INFO - 'tscrollx', 01:38:25 INFO - 'cart', 01:38:25 INFO - 'tsvg_static']}, 01:38:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:38:25 INFO - 'talos_options': ['--disable-e10s'], 01:38:25 INFO - 'tests': ['tp5o']}, 01:38:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:38:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:38:25 INFO - 'talos_options': ['--disable-e10s', 01:38:25 INFO - '--xperf_path', 01:38:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:38:25 INFO - 'tests': ['tp5n']}, 01:38:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:38:25 INFO - 'talos_options': ['--xperf_path', 01:38:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:38:25 INFO - 'tests': ['tp5n']}}, 01:38:25 INFO - 'talos.zip': {'path': '', 01:38:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:38:25 INFO - Running post-action listener: _resource_record_post_action 01:38:25 INFO - [mozharness: 2017-04-01 08:38:25.239297Z] Finished populate-webroot step (success) 01:38:25 INFO - [mozharness: 2017-04-01 08:38:25.239407Z] Running create-virtualenv step. 01:38:25 INFO - Running pre-action listener: _resource_record_pre_action 01:38:25 INFO - Running main action method: create_virtualenv 01:38:25 INFO - Creating virtualenv /builds/slave/test/build/venv 01:38:25 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 01:38:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:38:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:38:25 INFO - Using real prefix '/tools/python27' 01:38:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:38:26 INFO - Installing distribute.............................................................................................................................................................................................done. 01:38:28 INFO - Installing pip.................done. 01:38:28 INFO - Return code: 0 01:38:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:38:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:38:29 INFO - Reading from file tmpfile_stdout 01:38:29 INFO - Output received: 01:38:29 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) 01:38:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:38:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:29 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 0x108643b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108646768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2605e71a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108421d30>, '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 0x108348db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108416be8>, '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.NvfaUGUMVE/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.e1mudrhqHA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 01:38:29 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 01:38:29 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 01:38:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NvfaUGUMVE/Render', 01:38:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:29 INFO - 'HOME': '/Users/cltbld', 01:38:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:29 INFO - 'LOGNAME': 'cltbld', 01:38:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:29 INFO - 'MOZ_NO_REMOTE': '1', 01:38:29 INFO - 'NO_EM_RESTART': '1', 01:38:29 INFO - 'PAGER': '/bin/cat', 01:38:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:29 INFO - 'PWD': '/builds/slave/test', 01:38:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:29 INFO - 'SHELL': '/bin/bash', 01:38:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1mudrhqHA/Listeners', 01:38:29 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 01:38:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:29 INFO - 'USER': 'cltbld', 01:38:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:29 INFO - 'XPC_FLAGS': '0x0', 01:38:29 INFO - 'XPC_SERVICE_NAME': '0', 01:38:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:29 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 01:38:29 INFO - Cleaning up... 01:38:29 INFO - Return code: 0 01:38:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:38:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:29 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 0x108643b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108646768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2605e71a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108421d30>, '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 0x108348db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108416be8>, '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.NvfaUGUMVE/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.e1mudrhqHA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 01:38:29 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 01:38:29 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 01:38:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NvfaUGUMVE/Render', 01:38:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:29 INFO - 'HOME': '/Users/cltbld', 01:38:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:29 INFO - 'LOGNAME': 'cltbld', 01:38:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:29 INFO - 'MOZ_NO_REMOTE': '1', 01:38:29 INFO - 'NO_EM_RESTART': '1', 01:38:29 INFO - 'PAGER': '/bin/cat', 01:38:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:29 INFO - 'PWD': '/builds/slave/test', 01:38:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:29 INFO - 'SHELL': '/bin/bash', 01:38:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1mudrhqHA/Listeners', 01:38:29 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 01:38:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:29 INFO - 'USER': 'cltbld', 01:38:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:29 INFO - 'XPC_FLAGS': '0x0', 01:38:29 INFO - 'XPC_SERVICE_NAME': '0', 01:38:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:30 INFO - Downloading/unpacking psutil>=3.1.1 01:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:38:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:38:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:38:34 INFO - Installing collected packages: psutil 01:38:34 INFO - Running setup.py install for psutil 01:38:34 INFO - building 'psutil._psutil_osx' extension 01:38:34 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 01:38:34 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 01:38:34 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 01:38:34 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 01:38:35 INFO - building 'psutil._psutil_posix' extension 01:38:35 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 01:38:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:38:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:38:35 INFO - ^ 01:38:35 INFO - 1 warning generated. 01:38:35 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 01:38:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:38:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:38:35 INFO - Successfully installed psutil 01:38:35 INFO - Cleaning up... 01:38:35 INFO - Return code: 0 01:38:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:38:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108643b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108646768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2605e71a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108421d30>, '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 0x108348db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108416be8>, '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.NvfaUGUMVE/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.e1mudrhqHA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 01:38:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:38:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NvfaUGUMVE/Render', 01:38:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:35 INFO - 'HOME': '/Users/cltbld', 01:38:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:35 INFO - 'LOGNAME': 'cltbld', 01:38:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:35 INFO - 'MOZ_NO_REMOTE': '1', 01:38:35 INFO - 'NO_EM_RESTART': '1', 01:38:35 INFO - 'PAGER': '/bin/cat', 01:38:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:35 INFO - 'PWD': '/builds/slave/test', 01:38:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:35 INFO - 'SHELL': '/bin/bash', 01:38:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1mudrhqHA/Listeners', 01:38:35 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 01:38:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:35 INFO - 'USER': 'cltbld', 01:38:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:35 INFO - 'XPC_FLAGS': '0x0', 01:38:35 INFO - 'XPC_SERVICE_NAME': '0', 01:38:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:38:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:38:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:38:39 INFO - Installing collected packages: mozsystemmonitor 01:38:39 INFO - Running setup.py install for mozsystemmonitor 01:38:39 INFO - Successfully installed mozsystemmonitor 01:38:39 INFO - Cleaning up... 01:38:39 INFO - Return code: 0 01:38:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:38: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')]} 01:38:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38: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')]} 01:38:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38: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', '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 0x108643b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108646768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2605e71a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108421d30>, '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 0x108348db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108416be8>, '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.NvfaUGUMVE/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.e1mudrhqHA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 01:38: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:38: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 jsonschema==2.5.1 01:38:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NvfaUGUMVE/Render', 01:38:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:39 INFO - 'HOME': '/Users/cltbld', 01:38:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:39 INFO - 'LOGNAME': 'cltbld', 01:38:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:39 INFO - 'MOZ_NO_REMOTE': '1', 01:38:39 INFO - 'NO_EM_RESTART': '1', 01:38:39 INFO - 'PAGER': '/bin/cat', 01:38:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:39 INFO - 'PWD': '/builds/slave/test', 01:38:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:39 INFO - 'SHELL': '/bin/bash', 01:38:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1mudrhqHA/Listeners', 01:38:39 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 01:38:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:39 INFO - 'USER': 'cltbld', 01:38:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:39 INFO - 'XPC_FLAGS': '0x0', 01:38:39 INFO - 'XPC_SERVICE_NAME': '0', 01:38:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:39 INFO - Downloading/unpacking jsonschema==2.5.1 01:38:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:38:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:38:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:44 INFO - Downloading functools32-3.2.3-2.tar.gz 01:38:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:38:44 INFO - warning: no files found matching '*.txt' 01:38:44 INFO - no previously-included directories found matching 'build' 01:38:44 INFO - no previously-included directories found matching 'dist' 01:38:44 INFO - no previously-included directories found matching '.git*' 01:38:44 INFO - Installing collected packages: jsonschema, functools32 01:38:44 INFO - Running setup.py install for functools32 01:38:44 INFO - warning: no files found matching '*.txt' 01:38:44 INFO - no previously-included directories found matching 'build' 01:38:44 INFO - no previously-included directories found matching 'dist' 01:38:44 INFO - no previously-included directories found matching '.git*' 01:38:44 INFO - Successfully installed jsonschema functools32 01:38:44 INFO - Cleaning up... 01:38:44 INFO - Return code: 0 01:38:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:38:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108643b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108646768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2605e71a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108421d30>, '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 0x108348db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108416be8>, '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.NvfaUGUMVE/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.e1mudrhqHA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 01:38:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:38:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:38:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NvfaUGUMVE/Render', 01:38:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:44 INFO - 'HOME': '/Users/cltbld', 01:38:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:44 INFO - 'LOGNAME': 'cltbld', 01:38:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:44 INFO - 'MOZ_NO_REMOTE': '1', 01:38:44 INFO - 'NO_EM_RESTART': '1', 01:38:44 INFO - 'PAGER': '/bin/cat', 01:38:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:44 INFO - 'PWD': '/builds/slave/test', 01:38:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:44 INFO - 'SHELL': '/bin/bash', 01:38:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1mudrhqHA/Listeners', 01:38:44 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 01:38:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:44 INFO - 'USER': 'cltbld', 01:38:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:44 INFO - 'XPC_FLAGS': '0x0', 01:38:44 INFO - 'XPC_SERVICE_NAME': '0', 01:38:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:38:44 INFO - Cleaning up... 01:38:44 INFO - Return code: 0 01:38:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:38:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:45 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 0x108643b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108646768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2605e71a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108421d30>, '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 0x108348db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108416be8>, '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.NvfaUGUMVE/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.e1mudrhqHA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 01:38:45 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 01:38:45 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 01:38:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NvfaUGUMVE/Render', 01:38:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:45 INFO - 'HOME': '/Users/cltbld', 01:38:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:45 INFO - 'LOGNAME': 'cltbld', 01:38:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:45 INFO - 'MOZ_NO_REMOTE': '1', 01:38:45 INFO - 'NO_EM_RESTART': '1', 01:38:45 INFO - 'PAGER': '/bin/cat', 01:38:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:45 INFO - 'PWD': '/builds/slave/test', 01:38:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:45 INFO - 'SHELL': '/bin/bash', 01:38:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1mudrhqHA/Listeners', 01:38:45 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 01:38:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:45 INFO - 'USER': 'cltbld', 01:38:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:45 INFO - 'XPC_FLAGS': '0x0', 01:38:45 INFO - 'XPC_SERVICE_NAME': '0', 01:38:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:45 INFO - Downloading/unpacking blobuploader==1.2.4 01:38:45 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 01:38:45 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 01:38:45 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 01:38:45 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 01:38:45 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 01:38:45 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 01:38:48 INFO - Downloading blobuploader-1.2.4.tar.gz 01:38:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:38:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:38: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 01:38: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 01:38: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 01:38: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 01:38: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 01:38: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 01:38:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:38:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:38:49 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 01:38:49 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 01:38:49 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 01:38:49 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 01:38:49 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 01:38:49 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 01:38:49 INFO - Downloading docopt-0.6.1.tar.gz 01:38:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:38:49 INFO - Installing collected packages: blobuploader, requests, docopt 01:38:49 INFO - Running setup.py install for blobuploader 01:38:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:38:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:38:49 INFO - Running setup.py install for requests 01:38:50 INFO - Running setup.py install for docopt 01:38:50 INFO - Successfully installed blobuploader requests docopt 01:38:50 INFO - Cleaning up... 01:38:50 INFO - Return code: 0 01:38:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:50 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 0x108643b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108646768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2605e71a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108421d30>, '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 0x108348db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108416be8>, '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.NvfaUGUMVE/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.e1mudrhqHA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 01:38:50 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 01:38:50 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 01:38:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NvfaUGUMVE/Render', 01:38:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:50 INFO - 'HOME': '/Users/cltbld', 01:38:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:50 INFO - 'LOGNAME': 'cltbld', 01:38:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:50 INFO - 'MOZ_NO_REMOTE': '1', 01:38:50 INFO - 'NO_EM_RESTART': '1', 01:38:50 INFO - 'PAGER': '/bin/cat', 01:38:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:50 INFO - 'PWD': '/builds/slave/test', 01:38:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:50 INFO - 'SHELL': '/bin/bash', 01:38:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1mudrhqHA/Listeners', 01:38:50 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 01:38:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:50 INFO - 'USER': 'cltbld', 01:38:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:50 INFO - 'XPC_FLAGS': '0x0', 01:38:50 INFO - 'XPC_SERVICE_NAME': '0', 01:38:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:38:50 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-nQ5GND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:38:50 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-LUJpS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:38:51 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-rX1qQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:38:51 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-OiyZks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:38:51 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-tXX523-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:38:51 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-jpUtC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:38:51 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-u67Dn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:38:51 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-R5Nq3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:38:51 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-eTJsDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:38:51 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-UIvBAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:38:51 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-7cSl9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:38:52 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-K0F0LA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:38:52 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-V5tlqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:38:52 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-vaiIea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:38:52 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-04N8g_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:38:52 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-t_2wlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:38:52 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-x3J6UN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:38:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:38:52 INFO - Running setup.py install for manifestparser 01:38:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:38:52 INFO - Running setup.py install for mozcrash 01:38:52 INFO - Running setup.py install for mozdebug 01:38:53 INFO - Running setup.py install for mozdevice 01:38:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:38:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:38:53 INFO - Running setup.py install for mozfile 01:38:53 INFO - Running setup.py install for mozhttpd 01:38:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:38:53 INFO - Running setup.py install for mozinfo 01:38:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:38:53 INFO - Running setup.py install for mozInstall 01:38:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:38:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:38:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:38:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:38:53 INFO - Running setup.py install for mozleak 01:38:53 INFO - Running setup.py install for mozlog 01:38:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:38:54 INFO - Running setup.py install for moznetwork 01:38:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:38:54 INFO - Running setup.py install for mozprocess 01:38:54 INFO - Running setup.py install for mozprofile 01:38:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:38:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:38:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:38:54 INFO - Running setup.py install for mozrunner 01:38:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:38:54 INFO - Running setup.py install for mozscreenshot 01:38:54 INFO - Running setup.py install for moztest 01:38:55 INFO - Running setup.py install for mozversion 01:38:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:38:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:38:55 INFO - Cleaning up... 01:38:55 INFO - Return code: 0 01:38:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:38:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:55 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 0x108643b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108646768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2605e71a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108421d30>, '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 0x108348db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108416be8>, '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.NvfaUGUMVE/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.e1mudrhqHA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 01:38:55 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 01:38:55 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 01:38:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NvfaUGUMVE/Render', 01:38:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:55 INFO - 'HOME': '/Users/cltbld', 01:38:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:55 INFO - 'LOGNAME': 'cltbld', 01:38:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:55 INFO - 'MOZ_NO_REMOTE': '1', 01:38:55 INFO - 'NO_EM_RESTART': '1', 01:38:55 INFO - 'PAGER': '/bin/cat', 01:38:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:55 INFO - 'PWD': '/builds/slave/test', 01:38:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:55 INFO - 'SHELL': '/bin/bash', 01:38:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1mudrhqHA/Listeners', 01:38:55 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 01:38:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:55 INFO - 'USER': 'cltbld', 01:38:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:55 INFO - 'XPC_FLAGS': '0x0', 01:38:55 INFO - 'XPC_SERVICE_NAME': '0', 01:38:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:38:55 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-OOf4EQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:38:55 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)) 01:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:38:55 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-WO1iDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:38:55 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)) 01:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:38:55 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-s2ksth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:38:55 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)) 01:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:38:55 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-7NsTpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:38:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:38:55 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-a4u36k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:38:56 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)) 01:38:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:38:56 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-iCmhQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:38:56 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)) 01:38:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:38:56 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-7Atwh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:38:56 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)) 01:38:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:38:56 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-wi8lHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:38:56 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)) 01:38:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:38:56 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-zsj3Gc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:38:56 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)) 01:38:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:38:56 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-YpG2EE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:38:56 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)) 01:38:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:38:56 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-xqWXfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:38:56 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)) 01:38:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:38:56 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-ux1wzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:38:56 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)) 01:38:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:38:56 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-R0_J9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:38:56 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)) 01:38:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:38:56 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-1lRZQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:38:56 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)) 01:38:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:38:56 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-g3jU0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:38:57 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)) 01:38:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:38:57 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-BbRA7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:38:57 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)) 01:38:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:38:57 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-_VO6CU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:38:57 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)) 01:38:57 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)) 01:38:57 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)) 01:38:57 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)) 01:38:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:38:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:38:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:38:57 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 01:38:57 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 01:38:57 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 01:38:57 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 01:38:57 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 01:38:57 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 01:39:04 INFO - Downloading blessings-1.6.tar.gz 01:39:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:39:05 INFO - Installing collected packages: blessings 01:39:05 INFO - Running setup.py install for blessings 01:39:05 INFO - Successfully installed blessings 01:39:05 INFO - Cleaning up... 01:39:05 INFO - Return code: 0 01:39:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:39:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:39:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:39:05 INFO - Reading from file tmpfile_stdout 01:39:05 INFO - Current package versions: 01:39:05 INFO - blessings == 1.6 01:39:05 INFO - blobuploader == 1.2.4 01:39:05 INFO - docopt == 0.6.1 01:39:05 INFO - functools32 == 3.2.3-2 01:39:05 INFO - jsonschema == 2.5.1 01:39:05 INFO - manifestparser == 1.1 01:39:05 INFO - mozInstall == 1.12 01:39:05 INFO - mozcrash == 1.0 01:39:05 INFO - mozdebug == 0.1 01:39:05 INFO - mozdevice == 0.49 01:39:05 INFO - mozfile == 1.2 01:39:05 INFO - mozhttpd == 0.7 01:39:05 INFO - mozinfo == 0.9 01:39:05 INFO - mozleak == 0.1 01:39:05 INFO - mozlog == 3.4 01:39:05 INFO - moznetwork == 0.27 01:39:05 INFO - mozprocess == 0.25 01:39:05 INFO - mozprofile == 0.28 01:39:05 INFO - mozrunner == 6.13 01:39:05 INFO - mozscreenshot == 0.1 01:39:05 INFO - mozsystemmonitor == 0.3 01:39:05 INFO - moztest == 0.8 01:39:05 INFO - mozversion == 1.4 01:39:05 INFO - psutil == 3.1.1 01:39:05 INFO - requests == 1.2.3 01:39:05 INFO - wsgiref == 0.1.2 01:39:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:39:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:39:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:39:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:05 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 0x108643b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108646768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2605e71a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108421d30>, '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 0x108348db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108416be8>, '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.NvfaUGUMVE/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.e1mudrhqHA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 01:39:05 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 01:39:05 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 01:39:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NvfaUGUMVE/Render', 01:39:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:39:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:39:05 INFO - 'HOME': '/Users/cltbld', 01:39:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:39:05 INFO - 'LOGNAME': 'cltbld', 01:39:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:05 INFO - 'MOZ_NO_REMOTE': '1', 01:39:05 INFO - 'NO_EM_RESTART': '1', 01:39:05 INFO - 'PAGER': '/bin/cat', 01:39:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:39:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:05 INFO - 'PWD': '/builds/slave/test', 01:39:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:39:05 INFO - 'SHELL': '/bin/bash', 01:39:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1mudrhqHA/Listeners', 01:39:05 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 01:39:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:39:05 INFO - 'USER': 'cltbld', 01:39:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:39:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:39:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:05 INFO - 'XPC_FLAGS': '0x0', 01:39:05 INFO - 'XPC_SERVICE_NAME': '0', 01:39:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:39:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:39:05 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)) 01:39:05 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)) 01:39:05 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)) 01:39:05 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)) 01:39:05 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)) 01:39:05 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)) 01:39:05 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)) 01:39:05 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)) 01:39:05 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)) 01:39:05 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)) 01:39:05 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)) 01:39:05 INFO - Cleaning up... 01:39:05 INFO - Return code: 0 01:39:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:39:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:39:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:39:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:39:05 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 0x108643b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108646768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2605e71a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108421d30>, '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 0x108348db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108416be8>, '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.NvfaUGUMVE/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.e1mudrhqHA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 01:39:05 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 01:39:05 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 01:39:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NvfaUGUMVE/Render', 01:39:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:39:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:39:05 INFO - 'HOME': '/Users/cltbld', 01:39:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:39:05 INFO - 'LOGNAME': 'cltbld', 01:39:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:05 INFO - 'MOZ_NO_REMOTE': '1', 01:39:05 INFO - 'NO_EM_RESTART': '1', 01:39:05 INFO - 'PAGER': '/bin/cat', 01:39:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:39:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:05 INFO - 'PWD': '/builds/slave/test', 01:39:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:39:05 INFO - 'SHELL': '/bin/bash', 01:39:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1mudrhqHA/Listeners', 01:39:05 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 01:39:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:39:05 INFO - 'USER': 'cltbld', 01:39:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:39:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:39:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:05 INFO - 'XPC_FLAGS': '0x0', 01:39:05 INFO - 'XPC_SERVICE_NAME': '0', 01:39:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:39:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:39:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:39:06 INFO - Cleaning up... 01:39:06 INFO - Return code: 0 01:39:06 INFO - Running post-action listener: _resource_record_post_action 01:39:06 INFO - Running post-action listener: _start_resource_monitoring 01:39:06 INFO - Starting resource monitoring. 01:39:06 INFO - [mozharness: 2017-04-01 08:39:06.139788Z] Finished create-virtualenv step (success) 01:39:06 INFO - [mozharness: 2017-04-01 08:39:06.140562Z] Running install step. 01:39:06 INFO - Running pre-action listener: _resource_record_pre_action 01:39:06 INFO - Running main action method: install 01:39:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:39:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:39:06 INFO - Reading from file tmpfile_stdout 01:39:06 INFO - Detecting whether we're running mozinstall >=1.0... 01:39:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:39:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:39:06 INFO - Reading from file tmpfile_stdout 01:39:06 INFO - Output received: 01:39:06 INFO - Usage: mozinstall [options] installer 01:39:06 INFO - Options: 01:39:06 INFO - -h, --help show this help message and exit 01:39:06 INFO - -d DEST, --destination=DEST 01:39:06 INFO - Directory to install application into. [default: 01:39:06 INFO - "/builds/slave/test"] 01:39:06 INFO - --app=APP Application being installed. [default: firefox] 01:39:06 INFO - mkdir: /builds/slave/test/build/application 01:39:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:39:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 01:39:26 INFO - Reading from file tmpfile_stdout 01:39:26 INFO - Output received: 01:39:26 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 01:39:26 INFO - Running post-action listener: _resource_record_post_action 01:39:26 INFO - [mozharness: 2017-04-01 08:39:26.282666Z] Finished install step (success) 01:39:26 INFO - [mozharness: 2017-04-01 08:39:26.282803Z] Running run-tests step. 01:39:26 INFO - Running pre-action listener: _resource_record_pre_action 01:39:26 INFO - Running main action method: run_tests 01:39:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:39:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:39:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:39:26 INFO - 2017-04-01 01:39:26.295 screenresolution[2448:37555] starting screenresolution argv=screenresolution get 01:39:26 INFO - 2017-04-01 01:39:26.297 screenresolution[2448:37555] Display 0: 1600x1200x32@60 01:39:26 INFO - 2017-04-01 01:39:26.302 screenresolution[2449:37558] starting screenresolution argv=screenresolution list 01:39:26 INFO - Available Modes on Display 0 01:39:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:39:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:39:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:39:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:39:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:39:26 INFO - Intel Iris: 01:39:26 INFO - Chipset Model: Intel Iris 01:39:26 INFO - Type: GPU 01:39:26 INFO - Bus: Built-In 01:39:26 INFO - VRAM (Dynamic, Max): 1536 MB 01:39:26 INFO - Vendor: Intel (0x8086) 01:39:26 INFO - Device ID: 0x0a2e 01:39:26 INFO - Revision ID: 0x0009 01:39:26 INFO - Displays: 01:39:26 INFO - Display: 01:39:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:39:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:39:26 INFO - Display Serial Number: 1600x1200 60 01:39:26 INFO - Main Display: Yes 01:39:26 INFO - Mirror: Off 01:39:26 INFO - Online: Yes 01:39:26 INFO - Rotation: Supported 01:39:26 INFO - Return code: 0 01:39:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:39:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:39:26 INFO - Python 2.7.3 01:39:26 INFO - Return code: 0 01:39:26 INFO - grabbing minidump binary from tooltool 01:39: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')]} 01:39:26 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 0x108421d30>, '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 0x108348db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108416be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:39:26 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 01:39:26 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 01:39:26 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 01:39:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:39:26 INFO - Return code: 0 01:39:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:39:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:39:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:39:26 INFO - ENV: RUST_BACKTRACE is now 1 01:39:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:39:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:39:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0321', '--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 01:39:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0321 --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 01:39:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NvfaUGUMVE/Render', 01:39:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:39:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:39:26 INFO - 'HOME': '/Users/cltbld', 01:39:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:39:26 INFO - 'LOGNAME': 'cltbld', 01:39:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:39:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:39:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:39:26 INFO - 'MOZ_NO_REMOTE': '1', 01:39:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:39:26 INFO - 'NO_EM_RESTART': '1', 01:39:26 INFO - 'PAGER': '/bin/cat', 01:39:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:39:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:39:26 INFO - 'PWD': '/builds/slave/test', 01:39:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:39:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:39:26 INFO - 'RUST_BACKTRACE': '1', 01:39:26 INFO - 'SHELL': '/bin/bash', 01:39:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e1mudrhqHA/Listeners', 01:39:26 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 01:39:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:39:26 INFO - 'USER': 'cltbld', 01:39:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:39:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:39:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:39:26 INFO - 'XPC_FLAGS': '0x0', 01:39:26 INFO - 'XPC_SERVICE_NAME': '0', 01:39:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:39:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0321', '--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 01:39:26 INFO - mozversion application_buildid: 20170401004003 01:39:26 INFO - mozversion application_changeset: 500c5e8ec870c4dfe84effdfe1bd92f0bba5cc7f 01:39:26 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:39:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:39:26 INFO - mozversion application_name: Firefox 01:39:26 INFO - mozversion application_remotingname: firefox-dev 01:39:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:39:26 INFO - mozversion application_vendor: Mozilla 01:39:26 INFO - mozversion application_version: 54.0a2 01:39:26 INFO - mozversion platform_buildid: 20170401004003 01:39:26 INFO - mozversion platform_changeset: 500c5e8ec870c4dfe84effdfe1bd92f0bba5cc7f 01:39:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:39:26 INFO - mozversion platform_version: 54.0a2 01:39:26 INFO - using testdate: 1491035966 01:39:26 INFO - actual date: 1491035966 01:39:26 INFO - starting webserver on 'localhost:58942' 01:39:26 INFO - SUITE-START | Running 2 tests 01:39:26 INFO - TEST-START | dromaeo_css 01:39:26 INFO - operating with platform_type : mac_ 01:39:26 INFO - Initialising browser for dromaeo_css test... 01:39:26 INFO - TEST-INFO | started process 2459 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpAwJmCX/profile http://localhost:58942/getInfo.html) 01:39:29 INFO - PROCESS | 2459 | 2017-04-01 01:39:29.914 plugin-container[2462:37725] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x944b, name = 'com.apple.tsm.portname' 01:39:29 INFO - PROCESS | 2459 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:39:29 INFO - PROCESS | 2459 | 2017-04-01 01:39:29.914 plugin-container[2462:37725] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980f, name = 'com.apple.CFPasteboardClient' 01:39:29 INFO - PROCESS | 2459 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:39:29 INFO - PROCESS | 2459 | 2017-04-01 01:39:29.914 plugin-container[2462:37725] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:39:29 INFO - PROCESS | 2459 | __metrics Screen width/height:1600/1200 01:39:29 INFO - PROCESS | 2459 | colorDepth:24 01:39:29 INFO - PROCESS | 2459 | Browser inner width/height: 1024/689 01:39:29 INFO - PROCESS | 2459 | __metrics 01:39:30 ERROR - PROCESS | 2459 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 01:39:30 INFO - TEST-INFO | 2459: exit 0 01:39:30 INFO - Browser initialized. 01:39:30 INFO - Running cycle 1/1 for dromaeo_css test... 01:39:30 INFO - TEST-INFO | started process 2463 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpAwJmCX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:39:33 INFO - PROCESS | 2463 | 2017-04-01 01:39:33.130 plugin-container[2464:37859] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 01:39:33 INFO - PROCESS | 2463 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:39:33 INFO - PROCESS | 2463 | 2017-04-01 01:39:33.131 plugin-container[2464:37859] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c23, name = 'com.apple.CFPasteboardClient' 01:39:33 INFO - PROCESS | 2463 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:39:33 INFO - PROCESS | 2463 | 2017-04-01 01:39:33.131 plugin-container[2464:37859] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:39:42 INFO - PROCESS | 2463 | RSS: Main: 220733440 01:39:42 INFO - PROCESS | 2463 | 01:42:45 INFO - PROCESS | 2463 | Cycle 1(1): loaded http://localhost:58942/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58942/tests/dromaeo/cssquery-ext.html) 01:42:47 INFO - PROCESS | 2463 | RSS: Main: 241807360 01:42:47 INFO - PROCESS | 2463 | 01:44:31 INFO - PROCESS | 2463 | ************************* 01:44:31 INFO - PROCESS | 2463 | A coding exception was thrown and uncaught in a Task. 01:44:31 INFO - PROCESS | 2463 | 01:44:31 ERROR - PROCESS | 2463 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:44:31 INFO - PROCESS | 2463 | Full stack: 01:44:31 INFO - PROCESS | 2463 | ************************* 01:45:49 INFO - PROCESS | 2463 | Cycle 1(1): loaded http://localhost:58942/tests/dromaeo/cssquery-ext.html (next: http://localhost:58942/tests/dromaeo/cssquery-jquery.html) 01:45:50 INFO - PROCESS | 2463 | RSS: Main: 251592704 01:45:50 INFO - PROCESS | 2463 | 01:48:52 INFO - PROCESS | 2463 | Cycle 1(1): loaded http://localhost:58942/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58942/tests/dromaeo/cssquery-mootools.html) 01:48:52 INFO - PROCESS | 2463 | RSS: Main: 258084864 01:48:52 INFO - PROCESS | 2463 | 01:51:54 INFO - PROCESS | 2463 | Cycle 1(1): loaded http://localhost:58942/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58942/tests/dromaeo/cssquery-prototype.html) 01:51:55 INFO - PROCESS | 2463 | RSS: Main: 254570496 01:51:55 INFO - PROCESS | 2463 | 01:54:58 INFO - PROCESS | 2463 | Cycle 1(1): loaded http://localhost:58942/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58942/tests/dromaeo/cssquery-yui.html) 01:54:59 INFO - PROCESS | 2463 | RSS: Main: 259424256 01:54:59 INFO - PROCESS | 2463 | 01:58:01 INFO - PROCESS | 2463 | Cycle 1(1): loaded http://localhost:58942/tests/dromaeo/cssquery-yui.html (next: http://localhost:58942/tests/dromaeo/cssquery-dojo.html) 01:58:02 INFO - PROCESS | 2463 | RSS: Main: 256217088 01:58:02 INFO - PROCESS | 2463 | 01:58:02 INFO - PROCESS | 2463 | __start_tp_report 01:58:02 INFO - PROCESS | 2463 | _x_x_mozilla_page_load 01:58:02 INFO - PROCESS | 2463 | _x_x_mozilla_page_load_details 01:58:02 INFO - PROCESS | 2463 | |i|pagename|runs| 01:58:02 INFO - PROCESS | 2463 | |0;dojo.html;2726.4819684259996;2847.615571897793;3237.4820028795393;3412.0787387405403;3424.828758562073;4960.346214775096;5857.857717745042;5981.910271345933;5986.850328741778;6068.635881847093;5921.970390148054;5985.491233245169;6009.369016253296;6011.609245399057;6023.00620397635;5420.566354691615;5446;5464.098423760075;5479.972600136994;5503.477169668875;5493.340799104093;5496.917546236809;5512.5589952803675;5514.558835293187;5579;5284.471552844723;5311.973440132794;5507.394186639486;5510.118381059031;5517.503424691777;4259.31850903857;4590.196715574794;5357.892842143155;5457;5471.781128754885;4519.231730605772;5326.9467305327225;5457.399686034572;5476.479734425223;5546.223528705985;5685.9147112792925;5730.7421166047625;5797.507211886995;5933.961556727555;6099.664518451484;5318.361796584396;6258.186435763367;6319.052142178664;6321.715522801485;6324.430801227865;5703.9298925051435;5808.693044065094;6155.230596175478;6210.285817131043;6523.478121750249;5760.049591817387;6182.69086545671;6190.41191086846;6209.658468784261;6210.596311239755;5208.687478751287;6403.679816009181;6498.642574658439;6617.371349721769;6620;5362.249285100089;6225.62646241227;6268.153799237045;6294.905576416358;6295.37046295376;5739.794643124907;6263.686815659199;6342.682865856688;6443.194600674916;6463.838404039937;6206.534509911684;6254.21822272216;6259.217597800275;6261.436470717657;6330.905036424458;6312.810615681537;6317.46301564363;6335.493160547145;6338.207724034496;6353.332900045429;5000.874978125576;5038.168702164176;5089.618278629117;5129.948700513021;5184.922226166611;4467.486239082482;4554.157480866051;4572.74849883253;4598.724076555351;4666.603338716247;4478.888027799331;5915.970420147958;5987.940120598738;6055.273367195877;6058.363871793398;6382.585131966407;6440.001799721051;6461.321561236096;6462.224533056064;6481.5138864584405;6033.306169790442;6036.849078772978;6069.939300606938;6087.178230938766;6099.481544068715;5427.158790387496;5482.396936337;5547.889042219133;5605.887882242332;5618.466245706733;6637.966810165918;6647.335266473314;6647.3352664735075;6649.102371179925;6657.201135863727;6419.390157934989;6443.194600674916;6451.612903225821;6462.838429039311;6474.384933431316;6273.905891411541;6331.335209803088;6514.739410423719;6537.117489139096;6642.368974947372;4843.854684359475;6306.432421082125;6352.968235158794;6356.98288273874;6373.107764913008;6405.626684971166;6410.102585637921;6438.098666186644;6449.3873082057125;6461.967690161519;6054.878902422015;6120.325235636462;6181.35095814933;6229.470495007975;6288.748450061947;6227.688615569294;6250.3749625037135;6276.717547710273;6296.968515157578;6304.148939893055;5621.831345059655;5874.353821079592;6162.506999440124;6266.937330626635;6268.811935641938;6109;6117.0662765122115;6156.322804491592;6231.875362492816;6297.779577714758;5369.19462080691;6008.639481631116;6158.599691020069;6228.875422491434;6272.905906411316 01:58:02 INFO - PROCESS | 2463 | |1;ext.html;735.9852802943941;814.2793627639539;836.8075342671185;971.8542218667199;977.3500622086312;33844.66155338449;38164;38498.422523662135;40562.98592535188;40566.188676226455;37867.431988520126;38093.619063809354;38163.61836381636;38169.61830381702;38257.426138607945;7950.36397088233;8077.717279895205;9843.064908833649;10556.736081597945;10798.838017429745;10486.318389304672;10803.513841877135;10821.891781082206;10822.945885270563;10866.45667716615;10811.567537298499;10841.783164336708;10861.891381086207;10870.076043536308;10870.510827012804;10811.135109191246;10812.459377031117;10821.83767243492;10826.999999999922;10840.78318433631;10495.632652857113;10545.209109316833;10550.841737373945;10556.049955503964;10564.735881602945;33808.154796130046;33969;34067.14832129217;34097.806576769945;34427.79502717418;3661.286049220403;3669.853205871762;3736.327461057009;3745.7377983541164;3776.1126135358168;19359.225630974885;24247.757522424727;24399.756002439924;25294;25806.483870322678;3142.277276226458;3230.96769032309;3249.96750032499;3253.9511907321407;3279.1310302769784;306804;340451;344910;399571;409571.9521402374;43706.78146609246;43742.343864842056;43745;43816;43942.780286098365;44225.894352641444;44293.77853110778;44464.777676111415;44525;44607.330890036676;13423.060385772902;13498.122622029538;13532.729345413036;13537.59387218385;13601.523946661819;11507.424628768527;11545;11708.648740537797;11724.648260552198;11727.061835053175;11855.525778968917;11867.881321186678;11878.109141814399;11911.880881191077;11944.387507686524;4641.660047855266;5502;5542.168674698827;5566.749496272678;5592.105263157875;926.18495723764;947.1712251779693;949.5489642419795;980.8675828763169;980.9215262779103;23267;24083.51832963331;27382.726172738017;28140.85929570339;28679.713202867704;999.6850991937516;1033.2715435617872;1038.8285932821152;1067.1356201476829;1077.5582011375297;14369.353379097965;14651.706965860836;14692.412303507954;15040.947133700754;15054.774178387113;431911;433613;433992;435280;435327;45212.77393613011;54406;54918;55078.173827392624;56195.87608247812;1431.7566013777682;1457.0747575289768;1458.9197594132213;1459.2557795524358;1464.9633759155893;26808.927642894065;27108.051218207267;27308.590371144845;27930.720692793624;27938.580921286604;36524;43933.462328818336;43950.78024609856;43983.560164397946;43995;398674;399199;399480;399583;399803;29399.26501837471;30051;30127;30146.84926575353;30172.396552068396;30925.69074309228;37941;38635.647752328536;38650.61349386583;39188.6081139185;26326.86836565805;27381.17856464309;27567.31081722973;28190.71809281881;28216.012439564518;21407;26315;27500.862495687394;28114.71885281121;28350.14949551517;31611.84194079107;32121.839390802896;32379.19052023718;32456;32659;23973.880130599235;26845.865770671022;27044.594331084656;28929.710702893542;29841.850790745906;22050;22601.773982259965;22847.543049139258;23098.884505577364;23400.76599234054 01:58:02 INFO - PROCESS | 2463 | |2;jquery.html;358.56613497667814;406.71529929049666;413.5347733799476;415.1758758863657;454.96495472799376;20141.697874531874;20549.38351849445;21000.894995525003;21374.358769236947;21649.458763530918;21489.785102149013;21509.81696006723;21717.45706357342;21866;22145.889270553624;6780.796576102724;7050.541714788523;7098.361147496725;7104.928950710479;7120.25237350076;6854.485913556478;7232.059832221804;7582.810429739246;7752.728654497094;7753.379729621617;7209.567425954459;7551.358134558569;7658.731944381948;7712.57580833054;7746.961265193694;7465.328120469184;7605.80985475362;7614.4669873108905;7652.923470765277;7672.194419585947;7474.551526908349;7517.661705223278;7519.172890982042;7519.571281456506;7521.135069467026;24742.628860567107;25348;25566.744332556624;25809.483810323687;26300.73699263002;2798.3703666675037;2813.606095146681;2914.7231013053724;2921.1382642120625;2925.7513111385556;13571;14845.331960061823;15648.452304169414;16064.196790160446;16065.116418596972;2012.9194832206695;2038.551518665891;2045.120598142798;2045.273927755655;2045.5193029638024;133899.33050334782;158202;165542;165717;166328.16835915743;19487.902560487106;20276.391708248775;20414.795852041585;20454.59090818175;20489;31350.372992540022;31601.8419907899;31863.884764033148;31890.20274493156;32024.839875800473;10367.94816025915;10498.370097794157;10641.255112142077;10645.467726613639;10792.298500597593;7380.630968451556;8632;8835.74688454453;8844.955775221211;9001.459912405277;9506;9535.47554884474;9622.37454565451;9672.08115229052;9672.22622190223;3249.7075263226425;3912.4131380292615;4012.61880121388;4155.397467367215;4206.8737937861915;695.8503921656868;778.3228591125757;793.4445887878393;799.3285640062376;826.4669288309127;17394.47816565505;19836.801631983497;20492.795072049088;20531.28140515075;20677.89661051685;731.2650785960076;836.9790755231168;858.1204265627781;862.5040601654074;868.8957325121025;9391.577379017961;10369.325993810378;10611.25721199524;10621.73445663849;10665.306755060896;729488;766252;771368;788181;791439;94359;153785;160913.19543402208;164236;171898;1248.1949142802841;1275.355945247644;1289.645347529437;1295.954641587521;1299.402274953532;19662.21351145938;19954.90022549878;20195.394138175878;20277.986100694612;20393.898030509754;65319.34680653133;65378;65595;66179;68451.31548684649;92520.53739731258;92978;93278.13443730952;94212.52893735762;95393;11496.252743571642;11625.476853541442;11659.591914282959;12027.819582706092;12082.214656047328;13077.67305817324;13107.14803537767;13118.934405327913;13141.211527308393;13485.325733713471;11457.369844658624;11612.6516204514;11700.06399488056;11800.3509806958;11896.048316134515;10523.842142367717;10592.682219533426;10747.570097196025;10974.835377469184;10992.010719035325;10902.727431814268;10980.835287470534;11730.178887477794;11776;11962.10284228669;10166.745831354276;11749.236299640495;11893.881061189277;12110.757784844078;12110.878891210976;9945;11491.080713543064;11499.425028748696;11584.131190160602;11734.237274577124 01:58:02 INFO - PROCESS | 2463 | |3;mootools.html;1488.8957772955885;1495.9027663201891;1544.8686861616761;1581.778551002859;1588.539323596157;9047.41191822531;11230.719232019204;11260.887391126076;11269.830952535709;11575.53697852087;11160.553577856896;11294.09647228222;11327;11347.148963827705;11395.316281023144;2422.212780846228;2441.7192022917325;2455.3493324269084;2461.9905838606087;2466.2847774145475;2349.2012715676665;2433.7809597136256;2444.6210837320245;2450.5098980203943;2451.669024681663;2189.3760278320683;2254.8534345267585;2433.111914151331;2439.48770758141;2442.4748679034005;2084.3642688979853;2350.471143992605;2350.9647355289685;2409.8795060247007;2427.963580546293;2032.867863588862;2085.6871469279577;2358.1982126077046;2412.674288970984;2417.697787776528;10968.506417211245;11189.776204475866;11202.271852329653;11211.551537938472;11241.494132764044;5894.97052514739;6021.548383871227;6031.668258245794;6048.69756512177;6053.848653783647;3328.3509715605464;3523.8371337458834;3569.23261498779;3680;3762.661360477543;1189.8691143974156;1197.437204513877;1228.0535351467934;1249.9750004999848;1251.956181533651;13027.218366897921;13260.668483287995;14864.479743209044;15573.532794016197;15783.842161578352;4235.174141042528;4745.430548334222;4794.664373493822;4920.360353154067;4943.579795717333;11900.821487677691;11962.521499140112;11965.700857478458;11980.880191197977;12001.979831714227;5917.822465326047;5971.671558064349;5972.581919265696;5979.312379076462;5987.730552125165;5361.035013697494;5406.216098665672;5422.91865622016;5455.5908306877145;5491.643043202179;3915.4126880968083;3999.600039995977;4012.1574469361176;4088.4889388826396;4102.91794164121;915.2494954137543;1059.6397224943555;1061.2412125330425;1074.6453670288786;1075.655790147161;925.1627277314042;935.2985261054209;950.6197520991659;954.856771484283;1003.9799204015878;6639.734410623521;6665.933340666531;6685.765998190137;6921.930780692129;6934.375906168369;956.7225504603587;985.1183191043975;999.81003609314;1022.5960745505632;1033.478093562746;4139.875803725893;4265.0616864289805;4362.389265502832;4398.406215160976;4409.955900440955;13896.16623002623;14425.91805614583;14468.421263149356;14476.855231447551;14494.34775435108;13946.302684865715;14108.858911410754;14298.785518217235;14411;14422;8871.689490867917;10340.844887326695;10527.947360263302;10533.525991330409;10599.89400105989;6190.566660333733;6405.807825765234;6442.806715798533;6884.793456196322;7042.260562641054;9990.850137248093;11834.585789497327;11890;12215.206011609573;12236.327002014974;12182.025437964765;12200.877991219973;12240.816387754368;12381.257124572554;12416;8744.91255087441;8892.73321800347;8903.955480222558;8914.509701966454;8933.419327743675;10300.793984120126;10536.367817930948;10650.627228046982;10652.360858348524;10675.839862401916;8885.022647508898;9020.909790902007;9082.682106126254;9209.539523023688;9262.675806346746;8461.646136618112;8940;8946.776330591787;8961.955190224007;9015.233705135137;7249.710011599477;8015.198480151938;8098.716544921135;8104.594770261581;8110.148434414537;5759.7408116634015;5784.392638773036;5790.4209579043445;5959.404059594006;5993.430624090704;5901.409859014236;6000.609960352563;6016.819495415145;6149.877002459836;6248.0940263660605 01:58:02 INFO - PROCESS | 2463 | |4;prototype.html;377.6035163078768;382.521847690387;387.60851539944656;393.59290536332617;395.68740694851067;4384.232759267132;4543.681942264043;5076.543111119999;5165.9225111623355;5277.226782850261;4510.345999830022;4596;5175.8188463403785;5186.354958970924;5190.584753219743;4028.2678448267234;4158.729682570638;4288.41916999306;4325.286327755916;4356.607905288539;4279.646194592977;4300.720453170549;4334.739915605058;4341.305391137419;4407.2948328267485;4331.783410829462;4333.285007973696;4345.087531618364;4346.348047792841;4358.716683415583;3705.370087085202;4046.534648515429;4079.1433798902267;4210.1579684063;4355.147378467109;3399.932001359959;3764.8329018004233;3923.8228531440454;3933.406970177054;3986.86045988389;4524.3213517972235;4542.818287268506;4906.994066216417;5081.390233172006;5138.794448222066;4296.205202037633;4718.41019872516;4946.332245146895;4978.850634480971;5034.521720436553;5177.974110129463;5489.835304940858;5516.366252348007;5560.332760068818;5609.411011843738;3752.774833509971;4064;4424.871657727259;4440.511543730187;4487.730736155809;5197.064528384889;5492.533134683516;5628.521575666033;5910.0201432519725;5914.260717410324;3940.842366305373;4206.3690446432665;4478.619317357996;4483.843065492692;4499.3251012347755;4315.604839314211;4405.7797110144365;4612.616215135446;4673.649476289292;4681.695689780221;4226.894327641772;4808.8797780055775;4969.751512424365;4983.451820299764;4996.7001979881325;4792.7124372537755;5393.649412788156;5568.693721845338;5580.525655319262;5628.890909635907;1385.6005434511217;1519.9772003419957;1556.5875043113583;1562.9531114066597;1567.9529614111595;1022.6369638778275;1166.7107845830235;1181.4388165621433;1194.295365734221;1198.7173724115287;4618.445786505641;4770.688060783243;5181.948180518222;5227.947720522822;5265.867838414759;5145.588352931756;5246;5347.224652425377;5563.299012334571;5878.177764892896;4497.955020449754;4881.633877459205;5169.673646858929;5176.06830770465;5200.5438477226435;2779.81931174473;3000.7096948312437;3015.2311160653894;3023.425549145655;3034.423459542724;5297.576193904479;5687.232223649837;5719.742611582489;5957.344692083867;6008.3390827009;4997.85006449807;5058.342415486037;5064;5182.149817600305;5580.386157522669;5941.613795103304;5989.981703110481;6049.667268300198;6080.847978800565;6115.113308570232;5076.213186956028;5412.566994640498;5658.688772117408;5783.062674004255;5841.50347220491;5324.935012997338;5352.9464705354;5501.944980550144;5620.887582248414;5782.045962416322;5002.449730529712;5763.538916886723;5861.9413805861395;5980.803839232258;6109.969450152721;4270.829166833292;4554.5900868920635;4554.977225113853;4557.5442455754155;4589.567529209524;5022.1167062351615;5675.971620141873;5726.656400615976;5809.448102430262;5833.504152147115;5181.53366197044;5246.947530524646;5298.258243845788;5427.511523962704;5598.048331783688;5637.082227851603;5645.971770141123;5647.661140331593;5651.491365776936;5694.288213973254;4914.9017019658695;5135.460776618401;5202.063628546898;5549.528290095387;5558.972205138948;4620.435658209726;5058.645894787477;5196.804734910916;5360.731963401892;5386.515213630635;5533.253010843485;5539.667619942817;5569.387367389503;5577.888442231051;5618.88762224745 01:58:02 INFO - PROCESS | 2463 | |5;yui.html;708.7661071846292;717.2289788477389;749.7863109013932;767.0948281028387;865.3769286113999;15323.310451029702;15378.077315361086;16046.67906641868;16204.865659403846;16223;14497.63755906103;14784.704305913876;14980.850191498082;15055.849441505581;15109.39562417502;944.4474982135442;967.6468089147481;968.9680490277832;973.1776648731845;974.1184228273388;929.8000929800091;963.9325247232697;980.4901451245348;994.7363948553639;995.9840962218533;898.9640414383417;924.6948506992676;955.784948386611;1019.0930072235716;1027.5170669785189;1021.0249212002519;1021.9678125093635;1028.2956175020136;1028.5937054863361;1028.7942411517727;724.626817189148;732.1470486882776;746.5744525620398;762.393896852004;767.4896194031019;14487.492937747129;15140.713039391665;16247.83752162487;16581.336746530123;16676.24956876931;12076;13983.23092229927;14253.572392828231;14354.64113397163;14371.568852934428;10145.833229178665;11263.662090137226;11739.765204695943;11752.764944701059;11803.940980295129;3653.725970552219;3707.6848467880263;3709.536307961505;3727.5340582427198;3855.4409610606303;111380;112302.99999999919;115140;115297.84702152955;115465;10605.25763196569;11005.779884402427;11075.50160242791;11305.994319597257;11357.034652054503;22256.77743222547;22611.208607698652;22698.886505567367;23083.653745193835;23204.883975580015;7393.778186654409;7411.6664750086375;7774.727884524014;7851.29338359539;7871.685132594632;4328.09110086879;4416.271315233015;4429.734215947054;4507.774611269423;4555.2483840166005;3082.491388920803;3610.422332426799;3632.400653892078;3713.517242758478;3947.980260098681;702.2170280137711;702.6135625406006;708.553611224925;720.2293545905935;720.4308596209041;548.2571116137656;600.7747094839435;681.9395839469672;723.3381455967731;728.602911413281;10155.949220253999;10239.9488002561;10337.576863234382;10705.545066778472;10951.507182176822;624.9937500625026;637.3817397124782;639.8929851357178;641.5701480008405;644.9032645103272;4737.502562230985;5194.974025129925;5205;5217.739113044333;5228.215767634885;74193.62903185557;85632.71550926741;96112.51943740237;106883.32791680271;107947.46026269818;88934.55532722294;91744.54127729319;94598.5270073659;95400;95621.5218923901;65037.67481162659;65550.67224663847;69584.95622565666;74625;82795;11043.226974112056;11069.446527673423;11071.804569427037;11319.434028298387;11439;45397.30764233749;46621.53378466172;47056;47189.05621887612;47625.52374476211;52950.676233094484;61248.77502450015;62078;62170.689146555786;62997;12779.80830287565;12840.743185136433;12854.830274201031;13225.80161297562;13355.53255636048;14547.05444146127;14689.265536723335;14915.068493150686;15122.39510419571;15166.24168791578;13246.271455069678;13983.88128949702;14019;14169.362378692776;14251.287435628385;11362.659120226406;11700.35648039366;13283.870870975688;13846.169229846242;14005.579832605039;13226.338683066;13577.59267221985;13632.250226237653;13862.960277979393;14178.43286268579;8590.613422395882;9712.5143742814;9958.800823983625;10095.949520252352;10163;9054.094590540893;10222.48887555634;11111.222214444912;11521.423928803693;11668.066554675435 01:58:02 INFO - PROCESS | 2463 | __end_tp_report 01:58:02 INFO - PROCESS | 2463 | __start_cc_report 01:58:02 INFO - PROCESS | 2463 | _x_x_mozilla_cycle_collect,408 01:58:02 INFO - PROCESS | 2463 | __end_cc_report 01:58:02 INFO - PROCESS | 2463 | __startTimestamp1491037082172__endTimestamp 01:58:02 INFO - PROCESS | 2463 | 01:58:02 INFO - PROCESS | 2463 | ------- Summary: start ------- 01:58:02 INFO - PROCESS | 2463 | Number of tests: 6 01:58:02 INFO - PROCESS | 2463 | 01:58:02 INFO - PROCESS | 2463 | [#0] dojo.html Cycles:165 Average:5850.43 Median:6099.66 stddev:724.54 (11.9%) stddev-sans-first:684.06 01:58:02 INFO - PROCESS | 2463 | Values: 2726.5 2847.6 3237.5 3412.1 3424.8 4960.3 5857.9 5981.9 5986.9 6068.6 5922.0 5985.5 6009.4 6011.6 6023.0 5420.6 5446.0 5464.1 5480.0 5503.5 5493.3 5496.9 5512.6 5514.6 5579.0 5284.5 5312.0 5507.4 5510.1 5517.5 4259.3 4590.2 5357.9 5457.0 5471.8 4519.2 5326.9 5457.4 5476.5 5546.2 5685.9 5730.7 5797.5 5934.0 6099.7 5318.4 6258.2 6319.1 6321.7 6324.4 5703.9 5808.7 6155.2 6210.3 6523.5 5760.0 6182.7 6190.4 6209.7 6210.6 5208.7 6403.7 6498.6 6617.4 6620.0 5362.2 6225.6 6268.2 6294.9 6295.4 5739.8 6263.7 6342.7 6443.2 6463.8 6206.5 6254.2 6259.2 6261.4 6330.9 6312.8 6317.5 6335.5 6338.2 6353.3 5000.9 5038.2 5089.6 5129.9 5184.9 4467.5 4554.2 4572.7 4598.7 4666.6 4478.9 5916.0 5987.9 6055.3 6058.4 6382.6 6440.0 6461.3 6462.2 6481.5 6033.3 6036.8 6069.9 6087.2 6099.5 5427.2 5482.4 5547.9 5605.9 5618.5 6638.0 6647.3 6647.3 6649.1 6657.2 6419.4 6443.2 6451.6 6462.8 6474.4 6273.9 6331.3 6514.7 6537.1 6642.4 4843.9 6306.4 6353.0 6357.0 6373.1 6405.6 6410.1 6438.1 6449.4 6462.0 6054.9 6120.3 6181.4 6229.5 6288.7 6227.7 6250.4 6276.7 6297.0 6304.1 5621.8 5874.4 6162.5 6266.9 6268.8 6109.0 6117.1 6156.3 6231.9 6297.8 5369.2 6008.6 6158.6 6228.9 6272.9 01:58:02 INFO - PROCESS | 2463 | 01:58:02 INFO - PROCESS | 2463 | [#1] ext.html Cycles:180 Average:52492.24 Median:168488.20 stddev:105959.39 (62.9%) stddev-sans-first:106185.37 01:58:02 INFO - PROCESS | 2463 | Values: 736.0 814.3 836.8 971.9 977.4 33844.7 38164.0 38498.4 40563.0 40566.2 37867.4 38093.6 38163.6 38169.6 38257.4 7950.4 8077.7 9843.1 10556.7 10798.8 10486.3 10803.5 10821.9 10822.9 10866.5 10811.6 10841.8 10861.9 10870.1 10870.5 10811.1 10812.5 10821.8 10827.0 10840.8 10495.6 10545.2 10550.8 10556.0 10564.7 33808.2 33969.0 34067.1 34097.8 34427.8 3661.3 3669.9 3736.3 3745.7 3776.1 19359.2 24247.8 24399.8 25294.0 25806.5 3142.3 3231.0 3250.0 3254.0 3279.1 306804.0 340451.0 344910.0 399571.0 409572.0 43706.8 43742.3 43745.0 43816.0 43942.8 44225.9 44293.8 44464.8 44525.0 44607.3 13423.1 13498.1 13532.7 13537.6 13601.5 11507.4 11545.0 11708.6 11724.6 11727.1 11855.5 11867.9 11878.1 11911.9 11944.4 4641.7 5502.0 5542.2 5566.7 5592.1 926.2 947.2 949.5 980.9 980.9 23267.0 24083.5 27382.7 28140.9 28679.7 999.7 1033.3 1038.8 1067.1 1077.6 14369.4 14651.7 14692.4 15040.9 15054.8 431911.0 433613.0 433992.0 435280.0 435327.0 45212.8 54406.0 54918.0 55078.2 56195.9 1431.8 1457.1 1458.9 1459.3 1465.0 26808.9 27108.1 27308.6 27930.7 27938.6 36524.0 43933.5 43950.8 43983.6 43995.0 398674.0 399199.0 399480.0 399583.0 399803.0 29399.3 30051.0 30127.0 30146.8 30172.4 30925.7 37941.0 38635.6 38650.6 39188.6 26326.9 27381.2 27567.3 28190.7 28216.0 21407.0 26315.0 27500.9 28114.7 28350.1 31611.8 32121.8 32379.2 32456.0 32659.0 23973.9 26845.9 27044.6 28929.7 29841.9 22050.0 22601.8 22847.5 23098.9 23400.8 01:58:02 INFO - PROCESS | 2463 | 01:58:02 INFO - PROCESS | 2463 | [#2] jquery.html Cycles:180 Average:43933.52 Median:22005.94 stddev:128355.44 (583.3%) stddev-sans-first:128673.81 01:58:02 INFO - PROCESS | 2463 | Values: 358.6 406.7 413.5 415.2 455.0 20141.7 20549.4 21000.9 21374.4 21649.5 21489.8 21509.8 21717.5 21866.0 22145.9 6780.8 7050.5 7098.4 7104.9 7120.3 6854.5 7232.1 7582.8 7752.7 7753.4 7209.6 7551.4 7658.7 7712.6 7747.0 7465.3 7605.8 7614.5 7652.9 7672.2 7474.6 7517.7 7519.2 7519.6 7521.1 24742.6 25348.0 25566.7 25809.5 26300.7 2798.4 2813.6 2914.7 2921.1 2925.8 13571.0 14845.3 15648.5 16064.2 16065.1 2012.9 2038.6 2045.1 2045.3 2045.5 133899.3 158202.0 165542.0 165717.0 166328.2 19487.9 20276.4 20414.8 20454.6 20489.0 31350.4 31601.8 31863.9 31890.2 32024.8 10367.9 10498.4 10641.3 10645.5 10792.3 7380.6 8632.0 8835.7 8845.0 9001.5 9506.0 9535.5 9622.4 9672.1 9672.2 3249.7 3912.4 4012.6 4155.4 4206.9 695.9 778.3 793.4 799.3 826.5 17394.5 19836.8 20492.8 20531.3 20677.9 731.3 837.0 858.1 862.5 868.9 9391.6 10369.3 10611.3 10621.7 10665.3 729488.0 766252.0 771368.0 788181.0 791439.0 94359.0 153785.0 160913.2 164236.0 171898.0 1248.2 1275.4 1289.6 1296.0 1299.4 19662.2 19954.9 20195.4 20278.0 20393.9 65319.3 65378.0 65595.0 66179.0 68451.3 92520.5 92978.0 93278.1 94212.5 95393.0 11496.3 11625.5 11659.6 12027.8 12082.2 13077.7 13107.1 13118.9 13141.2 13485.3 11457.4 11612.7 11700.1 11800.4 11896.0 10523.8 10592.7 10747.6 10974.8 10992.0 10902.7 10980.8 11730.2 11776.0 11962.1 10166.7 11749.2 11893.9 12110.8 12110.9 9945.0 11491.1 11499.4 11584.1 11734.2 01:58:02 INFO - PROCESS | 2463 | 01:58:02 INFO - PROCESS | 2463 | [#3] mootools.html Cycles:180 Average:6797.20 Median:2453.51 stddev:4240.71 (172.8%) stddev-sans-first:4233.85 01:58:02 INFO - PROCESS | 2463 | Values: 1488.9 1495.9 1544.9 1581.8 1588.5 9047.4 11230.7 11260.9 11269.8 11575.5 11160.6 11294.1 11327.0 11347.1 11395.3 2422.2 2441.7 2455.3 2462.0 2466.3 2349.2 2433.8 2444.6 2450.5 2451.7 2189.4 2254.9 2433.1 2439.5 2442.5 2084.4 2350.5 2351.0 2409.9 2428.0 2032.9 2085.7 2358.2 2412.7 2417.7 10968.5 11189.8 11202.3 11211.6 11241.5 5895.0 6021.5 6031.7 6048.7 6053.8 3328.4 3523.8 3569.2 3680.0 3762.7 1189.9 1197.4 1228.1 1250.0 1252.0 13027.2 13260.7 14864.5 15573.5 15783.8 4235.2 4745.4 4794.7 4920.4 4943.6 11900.8 11962.5 11965.7 11980.9 12002.0 5917.8 5971.7 5972.6 5979.3 5987.7 5361.0 5406.2 5422.9 5455.6 5491.6 3915.4 3999.6 4012.2 4088.5 4102.9 915.2 1059.6 1061.2 1074.6 1075.7 925.2 935.3 950.6 954.9 1004.0 6639.7 6665.9 6685.8 6921.9 6934.4 956.7 985.1 999.8 1022.6 1033.5 4139.9 4265.1 4362.4 4398.4 4410.0 13896.2 14425.9 14468.4 14476.9 14494.3 13946.3 14108.9 14298.8 14411.0 14422.0 8871.7 10340.8 10527.9 10533.5 10599.9 6190.6 6405.8 6442.8 6884.8 7042.3 9990.9 11834.6 11890.0 12215.2 12236.3 12182.0 12200.9 12240.8 12381.3 12416.0 8744.9 8892.7 8904.0 8914.5 8933.4 10300.8 10536.4 10650.6 10652.4 10675.8 8885.0 9020.9 9082.7 9209.5 9262.7 8461.6 8940.0 8946.8 8962.0 9015.2 7249.7 8015.2 8098.7 8104.6 8110.1 5759.7 5784.4 5790.4 5959.4 5993.4 5901.4 6000.6 6016.8 6149.9 6248.1 01:58:02 INFO - PROCESS | 2463 | 01:58:02 INFO - PROCESS | 2463 | [#4] prototype.html Cycles:180 Average:4637.23 Median:5028.32 stddev:1292.48 (25.7%) stddev-sans-first:1255.94 01:58:02 INFO - PROCESS | 2463 | Values: 377.6 382.5 387.6 393.6 395.7 4384.2 4543.7 5076.5 5165.9 5277.2 4510.3 4596.0 5175.8 5186.4 5190.6 4028.3 4158.7 4288.4 4325.3 4356.6 4279.6 4300.7 4334.7 4341.3 4407.3 4331.8 4333.3 4345.1 4346.3 4358.7 3705.4 4046.5 4079.1 4210.2 4355.1 3399.9 3764.8 3923.8 3933.4 3986.9 4524.3 4542.8 4907.0 5081.4 5138.8 4296.2 4718.4 4946.3 4978.9 5034.5 5178.0 5489.8 5516.4 5560.3 5609.4 3752.8 4064.0 4424.9 4440.5 4487.7 5197.1 5492.5 5628.5 5910.0 5914.3 3940.8 4206.4 4478.6 4483.8 4499.3 4315.6 4405.8 4612.6 4673.6 4681.7 4226.9 4808.9 4969.8 4983.5 4996.7 4792.7 5393.6 5568.7 5580.5 5628.9 1385.6 1520.0 1556.6 1563.0 1568.0 1022.6 1166.7 1181.4 1194.3 1198.7 4618.4 4770.7 5181.9 5227.9 5265.9 5145.6 5246.0 5347.2 5563.3 5878.2 4498.0 4881.6 5169.7 5176.1 5200.5 2779.8 3000.7 3015.2 3023.4 3034.4 5297.6 5687.2 5719.7 5957.3 6008.3 4997.9 5058.3 5064.0 5182.1 5580.4 5941.6 5990.0 6049.7 6080.8 6115.1 5076.2 5412.6 5658.7 5783.1 5841.5 5324.9 5352.9 5501.9 5620.9 5782.0 5002.4 5763.5 5861.9 5980.8 6110.0 4270.8 4554.6 4555.0 4557.5 4589.6 5022.1 5676.0 5726.7 5809.4 5833.5 5181.5 5246.9 5298.3 5427.5 5598.0 5637.1 5646.0 5647.7 5651.5 5694.3 4914.9 5135.5 5202.1 5549.5 5559.0 4620.4 5058.6 5196.8 5360.7 5386.5 5533.3 5539.7 5569.4 5577.9 5618.9 01:58:02 INFO - PROCESS | 2463 | 01:58:02 INFO - PROCESS | 2463 | [#5] yui.html Cycles:180 Average:20219.58 Median:3346.46 stddev:29044.53 (867.9%) stddev-sans-first:29089.06 01:58:02 INFO - PROCESS | 2463 | Values: 708.8 717.2 749.8 767.1 865.4 15323.3 15378.1 16046.7 16204.9 16223.0 14497.6 14784.7 14980.9 15055.8 15109.4 944.4 967.6 969.0 973.2 974.1 929.8 963.9 980.5 994.7 996.0 899.0 924.7 955.8 1019.1 1027.5 1021.0 1022.0 1028.3 1028.6 1028.8 724.6 732.1 746.6 762.4 767.5 14487.5 15140.7 16247.8 16581.3 16676.2 12076.0 13983.2 14253.6 14354.6 14371.6 10145.8 11263.7 11739.8 11752.8 11803.9 3653.7 3707.7 3709.5 3727.5 3855.4 111380.0 112303.0 115140.0 115297.8 115465.0 10605.3 11005.8 11075.5 11306.0 11357.0 22256.8 22611.2 22698.9 23083.7 23204.9 7393.8 7411.7 7774.7 7851.3 7871.7 4328.1 4416.3 4429.7 4507.8 4555.2 3082.5 3610.4 3632.4 3713.5 3948.0 702.2 702.6 708.6 720.2 720.4 548.3 600.8 681.9 723.3 728.6 10155.9 10239.9 10337.6 10705.5 10951.5 625.0 637.4 639.9 641.6 644.9 4737.5 5195.0 5205.0 5217.7 5228.2 74193.6 85632.7 96112.5 106883.3 107947.5 88934.6 91744.5 94598.5 95400.0 95621.5 65037.7 65550.7 69585.0 74625.0 82795.0 11043.2 11069.4 11071.8 11319.4 11439.0 45397.3 46621.5 47056.0 47189.1 47625.5 52950.7 61248.8 62078.0 62170.7 62997.0 12779.8 12840.7 12854.8 13225.8 13355.5 14547.1 14689.3 14915.1 15122.4 15166.2 13246.3 13983.9 14019.0 14169.4 14251.3 11362.7 11700.4 13283.9 13846.2 14005.6 13226.3 13577.6 13632.3 13863.0 14178.4 8590.6 9712.5 9958.8 10095.9 10163.0 9054.1 10222.5 11111.2 11521.4 11668.1 01:58:02 INFO - PROCESS | 2463 | -------- Summary: end -------- 01:58:02 INFO - PROCESS | 2463 | 01:58:02 INFO - TEST-INFO | 2463: exit 0 01:58:02 INFO - TEST-OK | dromaeo_css | took 1115983ms 01:58:02 INFO - TEST-START | kraken 01:58:02 INFO - operating with platform_type : mac_ 01:58:02 INFO - Initialising browser for kraken test... 01:58:02 INFO - TEST-INFO | started process 2469 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpoujYLD/profile http://localhost:58942/getInfo.html) 01:58:05 INFO - PROCESS | 2469 | 2017-04-01 01:58:05.721 plugin-container[2471:40231] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.tsm.portname' 01:58:05 INFO - PROCESS | 2469 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:58:05 INFO - PROCESS | 2469 | 2017-04-01 01:58:05.721 plugin-container[2471:40231] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b5f, name = 'com.apple.CFPasteboardClient' 01:58:05 INFO - PROCESS | 2469 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:58:05 INFO - PROCESS | 2469 | 2017-04-01 01:58:05.721 plugin-container[2471:40231] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:58:05 INFO - PROCESS | 2469 | __metrics Screen width/height:1600/1200 01:58:05 INFO - PROCESS | 2469 | colorDepth:24 01:58:05 INFO - PROCESS | 2469 | Browser inner width/height: 1024/689 01:58:05 INFO - PROCESS | 2469 | __metrics 01:58:05 ERROR - PROCESS | 2469 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 01:58:06 INFO - TEST-INFO | 2469: exit 0 01:58:06 INFO - Browser initialized. 01:58:06 INFO - Running cycle 1/1 for kraken test... 01:58:06 INFO - TEST-INFO | started process 2472 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpoujYLD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:58:08 INFO - PROCESS | 2472 | 2017-04-01 01:58:08.934 plugin-container[2473:40359] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 01:58:08 INFO - PROCESS | 2472 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:58:08 INFO - PROCESS | 2472 | 2017-04-01 01:58:08.934 plugin-container[2473:40359] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 01:58:08 INFO - PROCESS | 2472 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:58:08 INFO - PROCESS | 2472 | 2017-04-01 01:58:08.935 plugin-container[2473:40359] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:58:18 INFO - PROCESS | 2472 | RSS: Main: 221028352 01:58:18 INFO - PROCESS | 2472 | 01:58:21 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:58942/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58942/tests/kraken/driver.html?testName=audio-beat-detection) 01:58:22 INFO - PROCESS | 2472 | RSS: Main: 235503616 01:58:22 INFO - PROCESS | 2472 | 01:58:25 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:58942/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58942/tests/kraken/driver.html?testName=audio-dft) 01:58:25 INFO - PROCESS | 2472 | RSS: Main: 243585024 01:58:25 INFO - PROCESS | 2472 | 01:58:29 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:58942/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58942/tests/kraken/driver.html?testName=audio-fft) 01:58:29 INFO - PROCESS | 2472 | RSS: Main: 247275520 01:58:29 INFO - PROCESS | 2472 | 01:58:31 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:58942/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58942/tests/kraken/driver.html?testName=audio-oscillator) 01:58:32 INFO - PROCESS | 2472 | RSS: Main: 248619008 01:58:32 INFO - PROCESS | 2472 | 01:58:34 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:58942/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58942/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:58:34 INFO - PROCESS | 2472 | RSS: Main: 248967168 01:58:34 INFO - PROCESS | 2472 | 01:58:37 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:58942/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58942/tests/kraken/driver.html?testName=imaging-darkroom) 01:58:38 INFO - PROCESS | 2472 | RSS: Main: 253042688 01:58:38 INFO - PROCESS | 2472 | 01:58:41 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:58942/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58942/tests/kraken/driver.html?testName=imaging-desaturate) 01:58:42 INFO - PROCESS | 2472 | RSS: Main: 252583936 01:58:42 INFO - PROCESS | 2472 | 01:58:45 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:58942/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58942/tests/kraken/driver.html?testName=json-parse-financial) 01:58:46 INFO - PROCESS | 2472 | RSS: Main: 252645376 01:58:46 INFO - PROCESS | 2472 | 01:58:47 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:58942/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58942/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:58:48 INFO - PROCESS | 2472 | RSS: Main: 251961344 01:58:48 INFO - PROCESS | 2472 | 01:58:50 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:58942/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58942/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:58:50 INFO - PROCESS | 2472 | RSS: Main: 251715584 01:58:50 INFO - PROCESS | 2472 | 01:58:53 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:58942/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58942/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:58:53 INFO - PROCESS | 2472 | RSS: Main: 251744256 01:58:53 INFO - PROCESS | 2472 | 01:58:56 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:58942/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58942/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:58:57 INFO - PROCESS | 2472 | RSS: Main: 252153856 01:58:57 INFO - PROCESS | 2472 | 01:59:00 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:58942/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58942/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:59:01 INFO - PROCESS | 2472 | RSS: Main: 251809792 01:59:01 INFO - PROCESS | 2472 | 01:59:02 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:58942/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58942/tests/kraken/driver.html?testName=ai-astar) 01:59:03 INFO - PROCESS | 2472 | RSS: Main: 253685760 01:59:03 INFO - PROCESS | 2472 | 01:59:03 INFO - PROCESS | 2472 | __start_tp_report 01:59:03 INFO - PROCESS | 2472 | _x_x_mozilla_page_load 01:59:03 INFO - PROCESS | 2472 | _x_x_mozilla_page_load_details 01:59:03 INFO - PROCESS | 2472 | |i|pagename|runs| 01:59:03 INFO - PROCESS | 2472 | |0;ai-astar;111;119;120;119;123;166;120;121;120;121 01:59:03 INFO - PROCESS | 2472 | |1;audio-beat-detection;160;140;115;143;116;140;142;111;128;112 01:59:03 INFO - PROCESS | 2472 | |2;audio-dft;200;199;194;200;231;256;200;196;194;196 01:59:03 INFO - PROCESS | 2472 | |3;audio-fft;80;58;59;89;61;60;60;60;77;60 01:59:03 INFO - PROCESS | 2472 | |4;audio-oscillator;82;70;87;73;73;70;82;70;71;82 01:59:03 INFO - PROCESS | 2472 | |5;imaging-gaussian-blur;112;88;88;92;88;89;102;89;88;88 01:59:03 INFO - PROCESS | 2472 | |6;imaging-darkroom;95;96;96;109;154;115;155;143;97;96 01:59:03 INFO - PROCESS | 2472 | |7;imaging-desaturate;91;98;91;92;96;88;149;93;143;86 01:59:03 INFO - PROCESS | 2472 | |8;json-parse-financial;65;65;64;65;83;106;77;68;72;106 01:59:03 INFO - PROCESS | 2472 | |9;json-stringify-tinderbox;68;88;65;65;58;58;53;53;50;54 01:59:03 INFO - PROCESS | 2472 | |10;stanford-crypto-aes;82;82;88;102;100;85;84;86;87;87 01:59:03 INFO - PROCESS | 2472 | |11;stanford-crypto-ccm;146;151;147;143;156;144;148;148;193;153 01:59:03 INFO - PROCESS | 2472 | |12;stanford-crypto-pbkdf2;166;171;167;166;166;167;169;173;225;167 01:59:03 INFO - PROCESS | 2472 | |13;stanford-crypto-sha256-iterative;68;67;66;66;85;86;83;84;84;71 01:59:03 INFO - PROCESS | 2472 | __end_tp_report 01:59:03 INFO - PROCESS | 2472 | __start_cc_report 01:59:03 INFO - PROCESS | 2472 | _x_x_mozilla_cycle_collect,989 01:59:03 INFO - PROCESS | 2472 | __end_cc_report 01:59:03 INFO - PROCESS | 2472 | __startTimestamp1491037143229__endTimestamp 01:59:03 INFO - PROCESS | 2472 | 01:59:03 INFO - PROCESS | 2472 | ------- Summary: start ------- 01:59:03 INFO - PROCESS | 2472 | Number of tests: 14 01:59:03 INFO - PROCESS | 2472 | 01:59:03 INFO - PROCESS | 2472 | [#0] ai-astar Cycles:10 Average:124.00 Median:120.50 stddev:15.09 (12.5%) stddev-sans-first:15.26 01:59:03 INFO - PROCESS | 2472 | Values: 111.0 119.0 120.0 119.0 123.0 166.0 120.0 121.0 120.0 121.0 01:59:03 INFO - PROCESS | 2472 | 01:59:03 INFO - PROCESS | 2472 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:140.00 stddev:16.73 (11.9%) stddev-sans-first:13.98 01:59:03 INFO - PROCESS | 2472 | Values: 160.0 140.0 115.0 143.0 116.0 140.0 142.0 111.0 128.0 112.0 01:59:03 INFO - PROCESS | 2472 | 01:59:03 INFO - PROCESS | 2472 | [#2] audio-dft Cycles:10 Average:206.60 Median:200.00 stddev:20.46 (10.2%) stddev-sans-first:21.56 01:59:03 INFO - PROCESS | 2472 | Values: 200.0 199.0 194.0 200.0 231.0 256.0 200.0 196.0 194.0 196.0 01:59:03 INFO - PROCESS | 2472 | 01:59:03 INFO - PROCESS | 2472 | [#3] audio-fft Cycles:10 Average:66.40 Median:60.50 stddev:11.19 (18.5%) stddev-sans-first:10.73 01:59:03 INFO - PROCESS | 2472 | Values: 80.0 58.0 59.0 89.0 61.0 60.0 60.0 60.0 77.0 60.0 01:59:03 INFO - PROCESS | 2472 | 01:59:03 INFO - PROCESS | 2472 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:77.50 stddev:6.50 (8.4%) stddev-sans-first:6.52 01:59:03 INFO - PROCESS | 2472 | Values: 82.0 70.0 87.0 73.0 73.0 70.0 82.0 70.0 71.0 82.0 01:59:03 INFO - PROCESS | 2472 | 01:59:03 INFO - PROCESS | 2472 | [#5] imaging-gaussian-blur Cycles:10 Average:92.40 Median:88.00 stddev:8.14 (9.3%) stddev-sans-first:4.60 01:59:03 INFO - PROCESS | 2472 | Values: 112.0 88.0 88.0 92.0 88.0 89.0 102.0 89.0 88.0 88.0 01:59:03 INFO - PROCESS | 2472 | 01:59:03 INFO - PROCESS | 2472 | [#6] imaging-darkroom Cycles:10 Average:115.60 Median:95.50 stddev:25.26 (26.5%) stddev-sans-first:25.67 01:59:03 INFO - PROCESS | 2472 | Values: 95.0 96.0 96.0 109.0 154.0 115.0 155.0 143.0 97.0 96.0 01:59:03 INFO - PROCESS | 2472 | 01:59:03 INFO - PROCESS | 2472 | [#7] imaging-desaturate Cycles:10 Average:102.70 Median:91.50 stddev:23.12 (25.3%) stddev-sans-first:24.14 01:59:03 INFO - PROCESS | 2472 | Values: 91.0 98.0 91.0 92.0 96.0 88.0 149.0 93.0 143.0 86.0 01:59:03 INFO - PROCESS | 2472 | 01:59:03 INFO - PROCESS | 2472 | [#8] json-parse-financial Cycles:10 Average:77.10 Median:66.50 stddev:16.41 (24.7%) stddev-sans-first:16.82 01:59:03 INFO - PROCESS | 2472 | Values: 65.0 65.0 64.0 65.0 83.0 106.0 77.0 68.0 72.0 106.0 01:59:03 INFO - PROCESS | 2472 | 01:59:03 INFO - PROCESS | 2472 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:61.50 stddev:11.18 (18.2%) stddev-sans-first:11.59 01:59:03 INFO - PROCESS | 2472 | Values: 68.0 88.0 65.0 65.0 58.0 58.0 53.0 53.0 50.0 54.0 01:59:03 INFO - PROCESS | 2472 | 01:59:03 INFO - PROCESS | 2472 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:85.50 stddev:7.01 (8.2%) stddev-sans-first:7.05 01:59:03 INFO - PROCESS | 2472 | Values: 82.0 82.0 88.0 102.0 100.0 85.0 84.0 86.0 87.0 87.0 01:59:03 INFO - PROCESS | 2472 | 01:59:03 INFO - PROCESS | 2472 | [#11] stanford-crypto-ccm Cycles:10 Average:152.90 Median:149.50 stddev:14.64 (9.8%) stddev-sans-first:15.31 01:59:03 INFO - PROCESS | 2472 | Values: 146.0 151.0 147.0 143.0 156.0 144.0 148.0 148.0 193.0 153.0 01:59:03 INFO - PROCESS | 2472 | 01:59:03 INFO - PROCESS | 2472 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.70 Median:168.00 stddev:18.18 (10.8%) stddev-sans-first:19.07 01:59:03 INFO - PROCESS | 2472 | Values: 166.0 171.0 167.0 166.0 166.0 167.0 169.0 173.0 225.0 167.0 01:59:03 INFO - PROCESS | 2472 | 01:59:03 INFO - PROCESS | 2472 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.00 Median:83.50 stddev:8.99 (10.8%) stddev-sans-first:9.06 01:59:03 INFO - PROCESS | 2472 | Values: 68.0 67.0 66.0 66.0 85.0 86.0 83.0 84.0 84.0 71.0 01:59:03 INFO - PROCESS | 2472 | -------- Summary: end -------- 01:59:03 INFO - PROCESS | 2472 | 01:59:04 INFO - TEST-INFO | 2472: exit 0 01:59:04 INFO - TEST-OK | kraken | took 61273ms 01:59:04 INFO - SUITE-END | took 1177s 01:59:04 INFO - Completed test suite (00:19:37) 01:59:04 INFO - javascript benchmark 01:59:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7667.207771994115, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2726.4819684259996, 2847.615571897793, 3237.4820028795393, 3412.0787387405403, 3424.828758562073, 4960.346214775096, 5857.857717745042, 5981.910271345933, 5986.850328741778, 6068.635881847093, 5921.970390148054, 5985.491233245169, 6009.369016253296, 6011.609245399057, 6023.00620397635, 5420.566354691615, 5446.0, 5464.098423760075, 5479.972600136994, 5503.477169668875, 5493.340799104093, 5496.917546236809, 5512.5589952803675, 5514.558835293187, 5579.0, 5284.471552844723, 5311.973440132794, 5507.394186639486, 5510.118381059031, 5517.503424691777, 4259.31850903857, 4590.196715574794, 5357.892842143155, 5457.0, 5471.781128754885, 4519.231730605772, 5326.9467305327225, 5457.399686034572, 5476.479734425223, 5546.223528705985, 5685.9147112792925, 5730.7421166047625, 5797.507211886995, 5933.961556727555, 6099.664518451484, 5318.361796584396, 6258.186435763367, 6319.052142178664, 6321.715522801485, 6324.430801227865, 5703.9298925051435, 5808.693044065094, 6155.230596175478, 6210.285817131043, 6523.478121750249, 5760.049591817387, 6182.69086545671, 6190.41191086846, 6209.658468784261, 6210.596311239755, 5208.687478751287, 6403.679816009181, 6498.642574658439, 6617.371349721769, 6620.0, 5362.249285100089, 6225.62646241227, 6268.153799237045, 6294.905576416358, 6295.37046295376, 5739.794643124907, 6263.686815659199, 6342.682865856688, 6443.194600674916, 6463.838404039937, 6206.534509911684, 6254.21822272216, 6259.217597800275, 6261.436470717657, 6330.905036424458, 6312.810615681537, 6317.46301564363, 6335.493160547145, 6338.207724034496, 6353.332900045429, 5000.874978125576, 5038.168702164176, 5089.618278629117, 5129.948700513021, 5184.922226166611, 4467.486239082482, 4554.157480866051, 4572.74849883253, 4598.724076555351, 4666.603338716247, 4478.888027799331, 5915.970420147958, 5987.940120598738, 6055.273367195877, 6058.363871793398, 6382.585131966407, 6440.001799721051, 6461.321561236096, 6462.224533056064, 6481.5138864584405, 6033.306169790442, 6036.849078772978, 6069.939300606938, 6087.178230938766, 6099.481544068715, 5427.158790387496, 5482.396936337, 5547.889042219133, 5605.887882242332, 5618.466245706733, 6637.966810165918, 6647.335266473314, 6647.3352664735075, 6649.102371179925, 6657.201135863727, 6419.390157934989, 6443.194600674916, 6451.612903225821, 6462.838429039311, 6474.384933431316, 6273.905891411541, 6331.335209803088, 6514.739410423719, 6537.117489139096, 6642.368974947372, 4843.854684359475, 6306.432421082125, 6352.968235158794, 6356.98288273874, 6373.107764913008, 6405.626684971166, 6410.102585637921, 6438.098666186644, 6449.3873082057125, 6461.967690161519, 6054.878902422015, 6120.325235636462, 6181.35095814933, 6229.470495007975, 6288.748450061947, 6227.688615569294, 6250.3749625037135, 6276.717547710273, 6296.968515157578, 6304.148939893055, 5621.831345059655, 5874.353821079592, 6162.506999440124, 6266.937330626635, 6268.811935641938, 6109.0, 6117.0662765122115, 6156.322804491592, 6231.875362492816, 6297.779577714758, 5369.19462080691, 6008.639481631116, 6158.599691020069, 6228.875422491434, 6272.905906411316], "value": 5802.345327644719, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.9852802943941, 814.2793627639539, 836.8075342671185, 971.8542218667199, 977.3500622086312, 33844.66155338449, 38164.0, 38498.422523662135, 40562.98592535188, 40566.188676226455, 37867.431988520126, 38093.619063809354, 38163.61836381636, 38169.61830381702, 38257.426138607945, 7950.36397088233, 8077.717279895205, 9843.064908833649, 10556.736081597945, 10798.838017429745, 10486.318389304672, 10803.513841877135, 10821.891781082206, 10822.945885270563, 10866.45667716615, 10811.567537298499, 10841.783164336708, 10861.891381086207, 10870.076043536308, 10870.510827012804, 10811.135109191246, 10812.459377031117, 10821.83767243492, 10826.999999999922, 10840.78318433631, 10495.632652857113, 10545.209109316833, 10550.841737373945, 10556.049955503964, 10564.735881602945, 33808.154796130046, 33969.0, 34067.14832129217, 34097.806576769945, 34427.79502717418, 3661.286049220403, 3669.853205871762, 3736.327461057009, 3745.7377983541164, 3776.1126135358168, 19359.225630974885, 24247.757522424727, 24399.756002439924, 25294.0, 25806.483870322678, 3142.277276226458, 3230.96769032309, 3249.96750032499, 3253.9511907321407, 3279.1310302769784, 306804.0, 340451.0, 344910.0, 399571.0, 409571.9521402374, 43706.78146609246, 43742.343864842056, 43745.0, 43816.0, 43942.780286098365, 44225.894352641444, 44293.77853110778, 44464.777676111415, 44525.0, 44607.330890036676, 13423.060385772902, 13498.122622029538, 13532.729345413036, 13537.59387218385, 13601.523946661819, 11507.424628768527, 11545.0, 11708.648740537797, 11724.648260552198, 11727.061835053175, 11855.525778968917, 11867.881321186678, 11878.109141814399, 11911.880881191077, 11944.387507686524, 4641.660047855266, 5502.0, 5542.168674698827, 5566.749496272678, 5592.105263157875, 926.18495723764, 947.1712251779693, 949.5489642419795, 980.8675828763169, 980.9215262779103, 23267.0, 24083.51832963331, 27382.726172738017, 28140.85929570339, 28679.713202867704, 999.6850991937516, 1033.2715435617872, 1038.8285932821152, 1067.1356201476829, 1077.5582011375297, 14369.353379097965, 14651.706965860836, 14692.412303507954, 15040.947133700754, 15054.774178387113, 431911.0, 433613.0, 433992.0, 435280.0, 435327.0, 45212.77393613011, 54406.0, 54918.0, 55078.173827392624, 56195.87608247812, 1431.7566013777682, 1457.0747575289768, 1458.9197594132213, 1459.2557795524358, 1464.9633759155893, 26808.927642894065, 27108.051218207267, 27308.590371144845, 27930.720692793624, 27938.580921286604, 36524.0, 43933.462328818336, 43950.78024609856, 43983.560164397946, 43995.0, 398674.0, 399199.0, 399480.0, 399583.0, 399803.0, 29399.26501837471, 30051.0, 30127.0, 30146.84926575353, 30172.396552068396, 30925.69074309228, 37941.0, 38635.647752328536, 38650.61349386583, 39188.6081139185, 26326.86836565805, 27381.17856464309, 27567.31081722973, 28190.71809281881, 28216.012439564518, 21407.0, 26315.0, 27500.862495687394, 28114.71885281121, 28350.14949551517, 31611.84194079107, 32121.839390802896, 32379.19052023718, 32456.0, 32659.0, 23973.880130599235, 26845.865770671022, 27044.594331084656, 28929.710702893542, 29841.850790745906, 22050.0, 22601.773982259965, 22847.543049139258, 23098.884505577364, 23400.76599234054], "value": 17819.446445108115, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.56613497667814, 406.71529929049666, 413.5347733799476, 415.1758758863657, 454.96495472799376, 20141.697874531874, 20549.38351849445, 21000.894995525003, 21374.358769236947, 21649.458763530918, 21489.785102149013, 21509.81696006723, 21717.45706357342, 21866.0, 22145.889270553624, 6780.796576102724, 7050.541714788523, 7098.361147496725, 7104.928950710479, 7120.25237350076, 6854.485913556478, 7232.059832221804, 7582.810429739246, 7752.728654497094, 7753.379729621617, 7209.567425954459, 7551.358134558569, 7658.731944381948, 7712.57580833054, 7746.961265193694, 7465.328120469184, 7605.80985475362, 7614.4669873108905, 7652.923470765277, 7672.194419585947, 7474.551526908349, 7517.661705223278, 7519.172890982042, 7519.571281456506, 7521.135069467026, 24742.628860567107, 25348.0, 25566.744332556624, 25809.483810323687, 26300.73699263002, 2798.3703666675037, 2813.606095146681, 2914.7231013053724, 2921.1382642120625, 2925.7513111385556, 13571.0, 14845.331960061823, 15648.452304169414, 16064.196790160446, 16065.116418596972, 2012.9194832206695, 2038.551518665891, 2045.120598142798, 2045.273927755655, 2045.5193029638024, 133899.33050334782, 158202.0, 165542.0, 165717.0, 166328.16835915743, 19487.902560487106, 20276.391708248775, 20414.795852041585, 20454.59090818175, 20489.0, 31350.372992540022, 31601.8419907899, 31863.884764033148, 31890.20274493156, 32024.839875800473, 10367.94816025915, 10498.370097794157, 10641.255112142077, 10645.467726613639, 10792.298500597593, 7380.630968451556, 8632.0, 8835.74688454453, 8844.955775221211, 9001.459912405277, 9506.0, 9535.47554884474, 9622.37454565451, 9672.08115229052, 9672.22622190223, 3249.7075263226425, 3912.4131380292615, 4012.61880121388, 4155.397467367215, 4206.8737937861915, 695.8503921656868, 778.3228591125757, 793.4445887878393, 799.3285640062376, 826.4669288309127, 17394.47816565505, 19836.801631983497, 20492.795072049088, 20531.28140515075, 20677.89661051685, 731.2650785960076, 836.9790755231168, 858.1204265627781, 862.5040601654074, 868.8957325121025, 9391.577379017961, 10369.325993810378, 10611.25721199524, 10621.73445663849, 10665.306755060896, 729488.0, 766252.0, 771368.0, 788181.0, 791439.0, 94359.0, 153785.0, 160913.19543402208, 164236.0, 171898.0, 1248.1949142802841, 1275.355945247644, 1289.645347529437, 1295.954641587521, 1299.402274953532, 19662.21351145938, 19954.90022549878, 20195.394138175878, 20277.986100694612, 20393.898030509754, 65319.34680653133, 65378.0, 65595.0, 66179.0, 68451.31548684649, 92520.53739731258, 92978.0, 93278.13443730952, 94212.52893735762, 95393.0, 11496.252743571642, 11625.476853541442, 11659.591914282959, 12027.819582706092, 12082.214656047328, 13077.67305817324, 13107.14803537767, 13118.934405327913, 13141.211527308393, 13485.325733713471, 11457.369844658624, 11612.6516204514, 11700.06399488056, 11800.3509806958, 11896.048316134515, 10523.842142367717, 10592.682219533426, 10747.570097196025, 10974.835377469184, 10992.010719035325, 10902.727431814268, 10980.835287470534, 11730.178887477794, 11776.0, 11962.10284228669, 10166.745831354276, 11749.236299640495, 11893.881061189277, 12110.757784844078, 12110.878891210976, 9945.0, 11491.080713543064, 11499.425028748696, 11584.131190160602, 11734.237274577124], "value": 11639.513131417898, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1488.8957772955885, 1495.9027663201891, 1544.8686861616761, 1581.778551002859, 1588.539323596157, 9047.41191822531, 11230.719232019204, 11260.887391126076, 11269.830952535709, 11575.53697852087, 11160.553577856896, 11294.09647228222, 11327.0, 11347.148963827705, 11395.316281023144, 2422.212780846228, 2441.7192022917325, 2455.3493324269084, 2461.9905838606087, 2466.2847774145475, 2349.2012715676665, 2433.7809597136256, 2444.6210837320245, 2450.5098980203943, 2451.669024681663, 2189.3760278320683, 2254.8534345267585, 2433.111914151331, 2439.48770758141, 2442.4748679034005, 2084.3642688979853, 2350.471143992605, 2350.9647355289685, 2409.8795060247007, 2427.963580546293, 2032.867863588862, 2085.6871469279577, 2358.1982126077046, 2412.674288970984, 2417.697787776528, 10968.506417211245, 11189.776204475866, 11202.271852329653, 11211.551537938472, 11241.494132764044, 5894.97052514739, 6021.548383871227, 6031.668258245794, 6048.69756512177, 6053.848653783647, 3328.3509715605464, 3523.8371337458834, 3569.23261498779, 3680.0, 3762.661360477543, 1189.8691143974156, 1197.437204513877, 1228.0535351467934, 1249.9750004999848, 1251.956181533651, 13027.218366897921, 13260.668483287995, 14864.479743209044, 15573.532794016197, 15783.842161578352, 4235.174141042528, 4745.430548334222, 4794.664373493822, 4920.360353154067, 4943.579795717333, 11900.821487677691, 11962.521499140112, 11965.700857478458, 11980.880191197977, 12001.979831714227, 5917.822465326047, 5971.671558064349, 5972.581919265696, 5979.312379076462, 5987.730552125165, 5361.035013697494, 5406.216098665672, 5422.91865622016, 5455.5908306877145, 5491.643043202179, 3915.4126880968083, 3999.600039995977, 4012.1574469361176, 4088.4889388826396, 4102.91794164121, 915.2494954137543, 1059.6397224943555, 1061.2412125330425, 1074.6453670288786, 1075.655790147161, 925.1627277314042, 935.2985261054209, 950.6197520991659, 954.856771484283, 1003.9799204015878, 6639.734410623521, 6665.933340666531, 6685.765998190137, 6921.930780692129, 6934.375906168369, 956.7225504603587, 985.1183191043975, 999.81003609314, 1022.5960745505632, 1033.478093562746, 4139.875803725893, 4265.0616864289805, 4362.389265502832, 4398.406215160976, 4409.955900440955, 13896.16623002623, 14425.91805614583, 14468.421263149356, 14476.855231447551, 14494.34775435108, 13946.302684865715, 14108.858911410754, 14298.785518217235, 14411.0, 14422.0, 8871.689490867917, 10340.844887326695, 10527.947360263302, 10533.525991330409, 10599.89400105989, 6190.566660333733, 6405.807825765234, 6442.806715798533, 6884.793456196322, 7042.260562641054, 9990.850137248093, 11834.585789497327, 11890.0, 12215.206011609573, 12236.327002014974, 12182.025437964765, 12200.877991219973, 12240.816387754368, 12381.257124572554, 12416.0, 8744.91255087441, 8892.73321800347, 8903.955480222558, 8914.509701966454, 8933.419327743675, 10300.793984120126, 10536.367817930948, 10650.627228046982, 10652.360858348524, 10675.839862401916, 8885.022647508898, 9020.909790902007, 9082.682106126254, 9209.539523023688, 9262.675806346746, 8461.646136618112, 8940.0, 8946.776330591787, 8961.955190224007, 9015.233705135137, 7249.710011599477, 8015.198480151938, 8098.716544921135, 8104.594770261581, 8110.148434414537, 5759.7408116634015, 5784.392638773036, 5790.4209579043445, 5959.404059594006, 5993.430624090704, 5901.409859014236, 6000.609960352563, 6016.819495415145, 6149.877002459836, 6248.0940263660605], "value": 5181.713544467929, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.6035163078768, 382.521847690387, 387.60851539944656, 393.59290536332617, 395.68740694851067, 4384.232759267132, 4543.681942264043, 5076.543111119999, 5165.9225111623355, 5277.226782850261, 4510.345999830022, 4596.0, 5175.8188463403785, 5186.354958970924, 5190.584753219743, 4028.2678448267234, 4158.729682570638, 4288.41916999306, 4325.286327755916, 4356.607905288539, 4279.646194592977, 4300.720453170549, 4334.739915605058, 4341.305391137419, 4407.2948328267485, 4331.783410829462, 4333.285007973696, 4345.087531618364, 4346.348047792841, 4358.716683415583, 3705.370087085202, 4046.534648515429, 4079.1433798902267, 4210.1579684063, 4355.147378467109, 3399.932001359959, 3764.8329018004233, 3923.8228531440454, 3933.406970177054, 3986.86045988389, 4524.3213517972235, 4542.818287268506, 4906.994066216417, 5081.390233172006, 5138.794448222066, 4296.205202037633, 4718.41019872516, 4946.332245146895, 4978.850634480971, 5034.521720436553, 5177.974110129463, 5489.835304940858, 5516.366252348007, 5560.332760068818, 5609.411011843738, 3752.774833509971, 4064.0, 4424.871657727259, 4440.511543730187, 4487.730736155809, 5197.064528384889, 5492.533134683516, 5628.521575666033, 5910.0201432519725, 5914.260717410324, 3940.842366305373, 4206.3690446432665, 4478.619317357996, 4483.843065492692, 4499.3251012347755, 4315.604839314211, 4405.7797110144365, 4612.616215135446, 4673.649476289292, 4681.695689780221, 4226.894327641772, 4808.8797780055775, 4969.751512424365, 4983.451820299764, 4996.7001979881325, 4792.7124372537755, 5393.649412788156, 5568.693721845338, 5580.525655319262, 5628.890909635907, 1385.6005434511217, 1519.9772003419957, 1556.5875043113583, 1562.9531114066597, 1567.9529614111595, 1022.6369638778275, 1166.7107845830235, 1181.4388165621433, 1194.295365734221, 1198.7173724115287, 4618.445786505641, 4770.688060783243, 5181.948180518222, 5227.947720522822, 5265.867838414759, 5145.588352931756, 5246.0, 5347.224652425377, 5563.299012334571, 5878.177764892896, 4497.955020449754, 4881.633877459205, 5169.673646858929, 5176.06830770465, 5200.5438477226435, 2779.81931174473, 3000.7096948312437, 3015.2311160653894, 3023.425549145655, 3034.423459542724, 5297.576193904479, 5687.232223649837, 5719.742611582489, 5957.344692083867, 6008.3390827009, 4997.85006449807, 5058.342415486037, 5064.0, 5182.149817600305, 5580.386157522669, 5941.613795103304, 5989.981703110481, 6049.667268300198, 6080.847978800565, 6115.113308570232, 5076.213186956028, 5412.566994640498, 5658.688772117408, 5783.062674004255, 5841.50347220491, 5324.935012997338, 5352.9464705354, 5501.944980550144, 5620.887582248414, 5782.045962416322, 5002.449730529712, 5763.538916886723, 5861.9413805861395, 5980.803839232258, 6109.969450152721, 4270.829166833292, 4554.5900868920635, 4554.977225113853, 4557.5442455754155, 4589.567529209524, 5022.1167062351615, 5675.971620141873, 5726.656400615976, 5809.448102430262, 5833.504152147115, 5181.53366197044, 5246.947530524646, 5298.258243845788, 5427.511523962704, 5598.048331783688, 5637.082227851603, 5645.971770141123, 5647.661140331593, 5651.491365776936, 5694.288213973254, 4914.9017019658695, 5135.460776618401, 5202.063628546898, 5549.528290095387, 5558.972205138948, 4620.435658209726, 5058.645894787477, 5196.804734910916, 5360.731963401892, 5386.515213630635, 5533.253010843485, 5539.667619942817, 5569.387367389503, 5577.888442231051, 5618.88762224745], "value": 4259.831117581532, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.7661071846292, 717.2289788477389, 749.7863109013932, 767.0948281028387, 865.3769286113999, 15323.310451029702, 15378.077315361086, 16046.67906641868, 16204.865659403846, 16223.0, 14497.63755906103, 14784.704305913876, 14980.850191498082, 15055.849441505581, 15109.39562417502, 944.4474982135442, 967.6468089147481, 968.9680490277832, 973.1776648731845, 974.1184228273388, 929.8000929800091, 963.9325247232697, 980.4901451245348, 994.7363948553639, 995.9840962218533, 898.9640414383417, 924.6948506992676, 955.784948386611, 1019.0930072235716, 1027.5170669785189, 1021.0249212002519, 1021.9678125093635, 1028.2956175020136, 1028.5937054863361, 1028.7942411517727, 724.626817189148, 732.1470486882776, 746.5744525620398, 762.393896852004, 767.4896194031019, 14487.492937747129, 15140.713039391665, 16247.83752162487, 16581.336746530123, 16676.24956876931, 12076.0, 13983.23092229927, 14253.572392828231, 14354.64113397163, 14371.568852934428, 10145.833229178665, 11263.662090137226, 11739.765204695943, 11752.764944701059, 11803.940980295129, 3653.725970552219, 3707.6848467880263, 3709.536307961505, 3727.5340582427198, 3855.4409610606303, 111380.0, 112302.99999999919, 115140.0, 115297.84702152955, 115465.0, 10605.25763196569, 11005.779884402427, 11075.50160242791, 11305.994319597257, 11357.034652054503, 22256.77743222547, 22611.208607698652, 22698.886505567367, 23083.653745193835, 23204.883975580015, 7393.778186654409, 7411.6664750086375, 7774.727884524014, 7851.29338359539, 7871.685132594632, 4328.09110086879, 4416.271315233015, 4429.734215947054, 4507.774611269423, 4555.2483840166005, 3082.491388920803, 3610.422332426799, 3632.400653892078, 3713.517242758478, 3947.980260098681, 702.2170280137711, 702.6135625406006, 708.553611224925, 720.2293545905935, 720.4308596209041, 548.2571116137656, 600.7747094839435, 681.9395839469672, 723.3381455967731, 728.602911413281, 10155.949220253999, 10239.9488002561, 10337.576863234382, 10705.545066778472, 10951.507182176822, 624.9937500625026, 637.3817397124782, 639.8929851357178, 641.5701480008405, 644.9032645103272, 4737.502562230985, 5194.974025129925, 5205.0, 5217.739113044333, 5228.215767634885, 74193.62903185557, 85632.71550926741, 96112.51943740237, 106883.32791680271, 107947.46026269818, 88934.55532722294, 91744.54127729319, 94598.5270073659, 95400.0, 95621.5218923901, 65037.67481162659, 65550.67224663847, 69584.95622565666, 74625.0, 82795.0, 11043.226974112056, 11069.446527673423, 11071.804569427037, 11319.434028298387, 11439.0, 45397.30764233749, 46621.53378466172, 47056.0, 47189.05621887612, 47625.52374476211, 52950.676233094484, 61248.77502450015, 62078.0, 62170.689146555786, 62997.0, 12779.80830287565, 12840.743185136433, 12854.830274201031, 13225.80161297562, 13355.53255636048, 14547.05444146127, 14689.265536723335, 14915.068493150686, 15122.39510419571, 15166.24168791578, 13246.271455069678, 13983.88128949702, 14019.0, 14169.362378692776, 14251.287435628385, 11362.659120226406, 11700.35648039366, 13283.870870975688, 13846.169229846242, 14005.579832605039, 13226.338683066, 13577.59267221985, 13632.250226237653, 13862.960277979393, 14178.43286268579, 8590.613422395882, 9712.5143742814, 9958.800823983625, 10095.949520252352, 10163.0, 9054.094590540893, 10222.48887555634, 11111.222214444912, 11521.423928803693, 11668.066554675435], "value": 7646.906763159004, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1543.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 119.0, 120.0, 119.0, 123.0, 166.0, 120.0, 121.0, 120.0, 121.0], "value": 124.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 140.0, 115.0, 143.0, 116.0, 140.0, 142.0, 111.0, 128.0, 112.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 199.0, 194.0, 200.0, 231.0, 256.0, 200.0, 196.0, 194.0, 196.0], "value": 206.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 59.0, 89.0, 61.0, 60.0, 60.0, 60.0, 77.0, 60.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 70.0, 87.0, 73.0, 73.0, 70.0, 82.0, 70.0, 71.0, 82.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 88.0, 88.0, 92.0, 88.0, 89.0, 102.0, 89.0, 88.0, 88.0], "value": 92.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 109.0, 154.0, 115.0, 155.0, 143.0, 97.0, 96.0], "value": 115.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 98.0, 91.0, 92.0, 96.0, 88.0, 149.0, 93.0, 143.0, 86.0], "value": 102.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 65.0, 83.0, 106.0, 77.0, 68.0, 72.0, 106.0], "value": 77.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 88.0, 65.0, 65.0, 58.0, 58.0, 53.0, 53.0, 50.0, 54.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 88.0, 102.0, 100.0, 85.0, 84.0, 86.0, 87.0, 87.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 151.0, 147.0, 143.0, 156.0, 144.0, 148.0, 148.0, 193.0, 153.0], "value": 152.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 171.0, 167.0, 166.0, 166.0, 167.0, 169.0, 173.0, 225.0, 167.0], "value": 173.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 66.0, 85.0, 86.0, 83.0, 84.0, 84.0, 71.0], "value": 76.0, "unit": "score"}]}]} 01:59:04 INFO - Return code: 0 01:59:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:59:04 ERROR - # TBPL SUCCESS # 01:59:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:59:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:59:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:59:04 INFO - 2017-04-01 01:59:04.547 screenresolution[2477:40749] starting screenresolution argv=screenresolution get 01:59:04 INFO - 2017-04-01 01:59:04.550 screenresolution[2477:40749] Display 0: 1600x1200x32@60 01:59:04 INFO - 2017-04-01 01:59:04.555 screenresolution[2478:40752] starting screenresolution argv=screenresolution list 01:59:04 INFO - Available Modes on Display 0 01:59:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:59:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:59:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:59:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:59:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:59:04 INFO - Intel Iris: 01:59:04 INFO - Chipset Model: Intel Iris 01:59:04 INFO - Type: GPU 01:59:04 INFO - Bus: Built-In 01:59:04 INFO - VRAM (Dynamic, Max): 1536 MB 01:59:04 INFO - Vendor: Intel (0x8086) 01:59:04 INFO - Device ID: 0x0a2e 01:59:04 INFO - Revision ID: 0x0009 01:59:04 INFO - Displays: 01:59:04 INFO - Display: 01:59:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:59:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:59:04 INFO - Display Serial Number: 1600x1200 60 01:59:04 INFO - Main Display: Yes 01:59:04 INFO - Mirror: Off 01:59:04 INFO - Online: Yes 01:59:04 INFO - Rotation: Supported 01:59:04 INFO - Return code: 0 01:59:04 INFO - Running post-action listener: _resource_record_post_action 01:59:04 INFO - [mozharness: 2017-04-01 08:59:04.791806Z] Finished run-tests step (success) 01:59:04 INFO - Running post-run listener: _resource_record_post_run 01:59:04 INFO - Total resource usage - Wall time: 1198s; CPU: 55.0%; Read bytes: 954368; Write bytes: 513629696; Read time: 7; Write time: 1618 01:59:04 INFO - TinderboxPrint: CPU usage
54.9% 01:59:04 INFO - TinderboxPrint: I/O read bytes / time
954,368 / 7 01:59:04 INFO - TinderboxPrint: I/O write bytes / time
513,629,696 / 1,618 01:59:04 INFO - TinderboxPrint: CPU idle
2,162.8 (45.1%) 01:59:04 INFO - TinderboxPrint: CPU system
970.9 (20.3%) 01:59:04 INFO - TinderboxPrint: CPU user
1,659.9 (34.6%) 01:59:04 INFO - TinderboxPrint: Swap in / out
208,609,280 / 0 01:59:04 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208978432; Write bytes: 209856000; Read time: 15900; Write time: 452 01:59:04 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 806912; Write bytes: 302262272; Read time: 4; Write time: 1157 01:59:05 INFO - Running post-run listener: _upload_blobber_files 01:59:05 INFO - Blob upload gear active. 01:59:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:59:05 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 01:59:05 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:59:05 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:59:05 INFO - (blobuploader) - INFO - Open directory for files ... 01:59:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:59:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:59:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:59:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:59:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:59:06 INFO - (blobuploader) - INFO - Done attempting. 01:59:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:59:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:59:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:59:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:59:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:59:07 INFO - (blobuploader) - INFO - Done attempting. 01:59:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:59:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:59:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:59:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:59:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:59:08 INFO - (blobuploader) - INFO - Done attempting. 01:59:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:59:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:59:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:59:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:59:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:59:10 INFO - (blobuploader) - INFO - Done attempting. 01:59:10 INFO - (blobuploader) - INFO - Iteration through files over. 01:59:10 INFO - Return code: 0 01:59:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:59:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:59:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/de506ce7a503215578bf4b700a6eae064a5a2b19d5ba7c870d28c158609b0d906039a16a29b0decf7ff2fe50fa9ce3a766b9bc6b429ffe66900284a746d37c8d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb6df4f066c48c9df72913ef5e5512e27e5e86383e94dda41607235a1dc09594187c3b9f3e0620f13c51eabfe2be209a83c53f714af1f6b3e010f41246662c35", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01214957e4f3d4ebbde4f10f39c2b318fc3df6da719923e4472db59109c829a6e0cca7e5e548bb778cf3ae5c2e6fd03c637a1c77eca3693291de21f06b8dc2e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/37e469f16e8d0252b74170176472bcb97369ce2a643766085b5eef242e25c63d4f12f29b1b1a631d80d69a9498a75ee8bf9cb7466cda2c82cf39ce2a205cb5d2"} 01:59:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:59:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:59:10 INFO - Contents: 01:59:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/de506ce7a503215578bf4b700a6eae064a5a2b19d5ba7c870d28c158609b0d906039a16a29b0decf7ff2fe50fa9ce3a766b9bc6b429ffe66900284a746d37c8d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb6df4f066c48c9df72913ef5e5512e27e5e86383e94dda41607235a1dc09594187c3b9f3e0620f13c51eabfe2be209a83c53f714af1f6b3e010f41246662c35", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01214957e4f3d4ebbde4f10f39c2b318fc3df6da719923e4472db59109c829a6e0cca7e5e548bb778cf3ae5c2e6fd03c637a1c77eca3693291de21f06b8dc2e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/37e469f16e8d0252b74170176472bcb97369ce2a643766085b5eef242e25c63d4f12f29b1b1a631d80d69a9498a75ee8bf9cb7466cda2c82cf39ce2a205cb5d2"} 01:59:10 INFO - Running post-run listener: copy_logs_to_upload_dir 01:59:10 INFO - Copying logs to upload dir... 01:59:10 INFO - mkdir: /builds/slave/test/build/upload/logs 01:59:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.480368 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-01 01:59:10.804282) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-01 01:59:10.808222) ========= 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.NvfaUGUMVE/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.e1mudrhqHA/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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/Mozilla-Aurora/sha512/de506ce7a503215578bf4b700a6eae064a5a2b19d5ba7c870d28c158609b0d906039a16a29b0decf7ff2fe50fa9ce3a766b9bc6b429ffe66900284a746d37c8d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb6df4f066c48c9df72913ef5e5512e27e5e86383e94dda41607235a1dc09594187c3b9f3e0620f13c51eabfe2be209a83c53f714af1f6b3e010f41246662c35", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01214957e4f3d4ebbde4f10f39c2b318fc3df6da719923e4472db59109c829a6e0cca7e5e548bb778cf3ae5c2e6fd03c637a1c77eca3693291de21f06b8dc2e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/37e469f16e8d0252b74170176472bcb97369ce2a643766085b5eef242e25c63d4f12f29b1b1a631d80d69a9498a75ee8bf9cb7466cda2c82cf39ce2a205cb5d2"} build_url:https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011452 build_url: 'https://queue.taskcluster.net/v1/task/JP1W0odGTwi9rS1uBTyndA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/de506ce7a503215578bf4b700a6eae064a5a2b19d5ba7c870d28c158609b0d906039a16a29b0decf7ff2fe50fa9ce3a766b9bc6b429ffe66900284a746d37c8d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb6df4f066c48c9df72913ef5e5512e27e5e86383e94dda41607235a1dc09594187c3b9f3e0620f13c51eabfe2be209a83c53f714af1f6b3e010f41246662c35", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01214957e4f3d4ebbde4f10f39c2b318fc3df6da719923e4472db59109c829a6e0cca7e5e548bb778cf3ae5c2e6fd03c637a1c77eca3693291de21f06b8dc2e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/37e469f16e8d0252b74170176472bcb97369ce2a643766085b5eef242e25c63d4f12f29b1b1a631d80d69a9498a75ee8bf9cb7466cda2c82cf39ce2a205cb5d2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-01 01:59:10.843613) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 01:59:10.844006) ========= 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.NvfaUGUMVE/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.e1mudrhqHA/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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.004718 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 01:59:10.893188) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-01 01:59:10.893639) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-01 01:59:10.894112) ========= ========= Total master_lag: 0.19 =========