builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0313 starttime: 1490613415.81 results: success (0) buildid: 20170327030203 builduid: 770911de535645b4b92b4b26645d7cc4 revision: cc53710589fb500610495da5258b7b9221edf681 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 04:16:55.805685) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 04:16:55.806162) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 04:16:55.806486) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 04:16:55.832765) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 04:16:55.833088) ========= 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.SUNZAxVopV/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.XXzOtccd7f/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-27 04:16:55-- 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% 20.0M=0.001s 2017-03-27 04:16:55 (20.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.136454 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 04:16:55.993820) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 04:16:55.994204) ========= 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.SUNZAxVopV/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.XXzOtccd7f/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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.027283 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 04:16:56.043693) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 04:16:56.044086) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cc53710589fb500610495da5258b7b9221edf681 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev cc53710589fb500610495da5258b7b9221edf681 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SUNZAxVopV/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.XXzOtccd7f/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-27 04:16:56,149 truncating revision to first 12 chars 2017-03-27 04:16:56,149 Setting DEBUG logging. 2017-03-27 04:16:56,149 attempt 1/10 2017-03-27 04:16:56,149 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cc53710589fb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 04:16:57,245 unpacking tar archive at: mozilla-central-cc53710589fb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.365700 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 04:16:57.433879) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 04:16:57.434212) ========= script_repo_revision: cc53710589fb500610495da5258b7b9221edf681 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 04:16:57.434625) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 04:16:57.434899) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 04:16:57.454608) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 43 secs) (at 2017-03-27 04:16:57.454982) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SUNZAxVopV/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.XXzOtccd7f/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 04:16:57 INFO - MultiFileLogger online at 20170327 04:16:57 in /builds/slave/test 04:16:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:16:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:16:57 INFO - {'append_to_log': False, 04:16:57 INFO - 'base_work_dir': '/builds/slave/test', 04:16:57 INFO - 'blob_upload_branch': 'Firefox', 04:16:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:16:57 INFO - 'branch': 'Firefox', 04:16:57 INFO - 'buildbot_json_path': 'buildprops.json', 04:16:57 INFO - 'config_files': ('talos/mac_config.py',), 04:16:57 INFO - 'default_actions': ('clobber', 04:16:57 INFO - 'read-buildbot-config', 04:16:57 INFO - 'download-and-extract', 04:16:57 INFO - 'populate-webroot', 04:16:57 INFO - 'create-virtualenv', 04:16:57 INFO - 'install', 04:16:57 INFO - 'run-tests'), 04:16:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:16:57 INFO - 'download_minidump_stackwalk': True, 04:16:57 INFO - 'download_symbols': 'ondemand', 04:16:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:16:57 INFO - 'tooltool.py': '/tools/tooltool.py', 04:16:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:16:57 INFO - '/tools/misc-python/virtualenv.py')}, 04:16:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:16:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:16:57 INFO - 'gecko_profile': False, 04:16:57 INFO - 'gecko_profile_interval': 0, 04:16:57 INFO - 'installer_path': 'installer.exe', 04:16:57 INFO - 'log_level': 'info', 04:16:57 INFO - 'log_name': 'talos', 04:16:57 INFO - 'log_to_console': True, 04:16:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:16:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:16:57 INFO - 'opt_config_files': (), 04:16:57 INFO - 'pip_index': False, 04:16:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:16:57 INFO - 'cmd': ('bash', 04:16:57 INFO - '-c', 04:16:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:16:57 INFO - 'enabled': True, 04:16:57 INFO - 'halt_on_failure': False, 04:16:57 INFO - 'name': 'check_screen_resolution'},), 04:16:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:16:57 INFO - 'cmd': ('bash', 04:16:57 INFO - '-c', 04:16:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:16:57 INFO - 'enabled': True, 04:16:57 INFO - 'halt_on_failure': False, 04:16:57 INFO - 'name': 'check_screen_resolution'},), 04:16:57 INFO - 'run_cmd_checks_enabled': True, 04:16:57 INFO - 'suite': 'dromaeojs', 04:16:57 INFO - 'system_bits': '32', 04:16:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:16:57 INFO - 'title': 't-yosemite-r7-0313', 04:16:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:16:57 INFO - 'use_talos_json': True, 04:16:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:16:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:16:57 INFO - 'work_dir': 'build'} 04:16:57 INFO - [mozharness: 2017-03-27 11:16:57.666300Z] Running clobber step. 04:16:57 INFO - Running pre-action listener: _resource_record_pre_action 04:16:57 INFO - Running main action method: clobber 04:16:57 INFO - rmtree: /builds/slave/test/build 04:16:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:17:00 INFO - Running post-action listener: _resource_record_post_action 04:17:00 INFO - [mozharness: 2017-03-27 11:17:00.071146Z] Finished clobber step (success) 04:17:00 INFO - [mozharness: 2017-03-27 11:17:00.071348Z] Running read-buildbot-config step. 04:17:00 INFO - Running pre-action listener: _resource_record_pre_action 04:17:00 INFO - Running main action method: read_buildbot_config 04:17:00 INFO - Using buildbot properties: 04:17:00 INFO - { 04:17:00 INFO - "project": "", 04:17:00 INFO - "product": "firefox", 04:17:00 INFO - "script_repo_revision": "production", 04:17:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:17:00 INFO - "repository": "", 04:17:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:17:00 INFO - "buildid": "20170327030203", 04:17:00 INFO - "stage_platform": "macosx64", 04:17:00 INFO - "basedir": "/builds/slave/test", 04:17:00 INFO - "nightly_build": "True", 04:17:00 INFO - "buildnumber": 15, 04:17:00 INFO - "slavename": "t-yosemite-r7-0313", 04:17:00 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:17:00 INFO - "platform": "yosemite_r7", 04:17:00 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:17:00 INFO - "branch": "mozilla-central", 04:17:00 INFO - "repo_path": "mozilla-central", 04:17:00 INFO - "revision": "cc53710589fb500610495da5258b7b9221edf681", 04:17:00 INFO - "pgo_build": "False", 04:17:00 INFO - "builduid": "770911de535645b4b92b4b26645d7cc4", 04:17:00 INFO - "slavebuilddir": "test" 04:17:00 INFO - } 04:17:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:17:00 INFO - Running post-action listener: _resource_record_post_action 04:17:00 INFO - [mozharness: 2017-03-27 11:17:00.074862Z] Finished read-buildbot-config step (success) 04:17:00 INFO - [mozharness: 2017-03-27 11:17:00.075005Z] Running download-and-extract step. 04:17:00 INFO - Running pre-action listener: _resource_record_pre_action 04:17:00 INFO - Running main action method: download_and_extract 04:17:00 INFO - mkdir: /builds/slave/test/build/tests 04:17:00 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')]} 04:17:00 INFO - trying https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:17:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:17:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 04:17:01 INFO - Downloaded 1325 bytes. 04:17:01 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:17:01 INFO - Using the following test package requirements: 04:17:01 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:17:01 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:17:01 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:17:01 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:17:01 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:17:01 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:17:01 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:17:01 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:17:01 INFO - u'jsshell-mac.zip'], 04:17:01 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:17:01 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:17:01 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:17:01 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:17:01 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:17:01 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:17:01 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:17:01 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:17:01 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:17:01 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:17:01 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:17:01 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:17:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:17:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:17:01 INFO - Fetch https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:17:03 INFO - Expected file size: 52413476 04:17:03 INFO - Obtained file size: 52413476 04:17:19 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:17:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:17:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:17:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:17:21 INFO - Expected file size: 52413476 04:17:21 INFO - Obtained file size: 52413476 04:17:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:17:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:17:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:17:37 INFO - Expected file size: 14404171 04:17:37 INFO - Obtained file size: 14404171 04:17:37 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')]} 04:17:37 INFO - trying https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:17:37 INFO - Downloading https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:17:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 04:17:39 INFO - Downloaded 75669010 bytes. 04:17:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:17:39 INFO - mkdir: /builds/slave/test/properties 04:17:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:17:39 INFO - Writing to file /builds/slave/test/properties/build_url 04:17:39 INFO - Contents: 04:17:39 INFO - build_url:https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:17:40 INFO - Running post-action listener: _resource_record_post_action 04:17:40 INFO - Running post-action listener: set_extra_try_arguments 04:17:40 INFO - [mozharness: 2017-03-27 11:17:40.514794Z] Finished download-and-extract step (success) 04:17:40 INFO - [mozharness: 2017-03-27 11:17:40.514923Z] Running populate-webroot step. 04:17:40 INFO - Running pre-action listener: _resource_record_pre_action 04:17:40 INFO - Running main action method: populate_webroot 04:17:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:17:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:17:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:17:40 INFO - '--tppagecycles', 04:17:40 INFO - '7'], 04:17:40 INFO - 'tests': ['tsvgm']}}, 04:17:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:17:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:17:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:17:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:17:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:17:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:17:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:17:40 INFO - 'talos_options': ['--disable-e10s'], 04:17:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:17:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:17:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:17:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:17:40 INFO - 'talos_options': ['--disable-e10s'], 04:17:40 INFO - 'tests': ['damp', 'tps']}, 04:17:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:17:40 INFO - 'tests': ['damp', 'tps']}, 04:17:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:17:40 INFO - 'tests': ['dromaeo_dom']}, 04:17:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:17:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:17:40 INFO - 'tests': ['basic_compositor_video']}, 04:17:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:17:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:17:40 INFO - 'tests': ['a11yr', 04:17:40 INFO - 'ts_paint', 04:17:40 INFO - 'tpaint', 04:17:40 INFO - 'sessionrestore', 04:17:40 INFO - 'sessionrestore_no_auto_restore', 04:17:40 INFO - 'tabpaint']}, 04:17:40 INFO - 'other-e10s': {'tests': ['a11yr', 04:17:40 INFO - 'ts_paint', 04:17:40 INFO - 'tpaint', 04:17:40 INFO - 'sessionrestore', 04:17:40 INFO - 'sessionrestore_no_auto_restore', 04:17:40 INFO - 'tabpaint']}, 04:17:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:17:40 INFO - 'ts_paint', 04:17:40 INFO - 'tpaint', 04:17:40 INFO - 'sessionrestore', 04:17:40 INFO - 'sessionrestore_no_auto_restore', 04:17:40 INFO - 'tabpaint']}, 04:17:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:17:40 INFO - 'ts_paint', 04:17:40 INFO - 'tpaint', 04:17:40 INFO - 'sessionrestore', 04:17:40 INFO - 'sessionrestore_no_auto_restore', 04:17:40 INFO - 'tabpaint']}, 04:17:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:17:40 INFO - 'tests': ['a11yr', 04:17:40 INFO - 'ts_paint', 04:17:40 INFO - 'tpaint', 04:17:40 INFO - 'sessionrestore', 04:17:40 INFO - 'sessionrestore_no_auto_restore', 04:17:40 INFO - 'tabpaint']}, 04:17:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:17:40 INFO - 'tests': ['a11yr', 04:17:40 INFO - 'ts_paint', 04:17:40 INFO - 'tpaint', 04:17:40 INFO - 'sessionrestore', 04:17:40 INFO - 'sessionrestore_no_auto_restore', 04:17:40 INFO - 'tabpaint']}, 04:17:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:17:40 INFO - 'tests': ['tsvgx', 04:17:40 INFO - 'tsvgr_opacity', 04:17:40 INFO - 'tart', 04:17:40 INFO - 'tscrollx', 04:17:40 INFO - 'cart', 04:17:40 INFO - 'tsvg_static']}, 04:17:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:17:40 INFO - 'tsvgr_opacity', 04:17:40 INFO - 'tart', 04:17:40 INFO - 'tscrollx', 04:17:40 INFO - 'cart', 04:17:40 INFO - 'tsvg_static']}, 04:17:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:17:40 INFO - 'talos_options': ['--disable-e10s'], 04:17:40 INFO - 'tests': ['tp5o']}, 04:17:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:17:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:17:40 INFO - 'talos_options': ['--disable-e10s', 04:17:40 INFO - '--xperf_path', 04:17:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:17:40 INFO - 'tests': ['tp5n']}, 04:17:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:17:40 INFO - 'talos_options': ['--xperf_path', 04:17:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:17:40 INFO - 'tests': ['tp5n']}}, 04:17:40 INFO - 'talos.zip': {'path': '', 04:17:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:17:40 INFO - Running post-action listener: _resource_record_post_action 04:17:40 INFO - [mozharness: 2017-03-27 11:17:40.533750Z] Finished populate-webroot step (success) 04:17:40 INFO - [mozharness: 2017-03-27 11:17:40.533866Z] Running create-virtualenv step. 04:17:40 INFO - Running pre-action listener: _resource_record_pre_action 04:17:40 INFO - Running main action method: create_virtualenv 04:17:40 INFO - Creating virtualenv /builds/slave/test/build/venv 04:17:40 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 04:17:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:17:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:17:40 INFO - Using real prefix '/tools/python27' 04:17:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:17:41 INFO - Installing distribute.............................................................................................................................................................................................done. 04:17:44 INFO - Installing pip.................done. 04:17:44 INFO - Return code: 0 04:17:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:17:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:17:44 INFO - Reading from file tmpfile_stdout 04:17:44 INFO - Output received: 04:17:44 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) 04:17:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:17: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')]} 04:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17: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')]} 04:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1075c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94d357f5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a3d30>, '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 0x1072cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107398be8>, '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.SUNZAxVopV/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.XXzOtccd7f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 04:17: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', 'pip>=1.5'] in /builds/slave/test/build 04:17: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 pip>=1.5 04:17:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SUNZAxVopV/Render', 04:17:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:17:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:17:44 INFO - 'HOME': '/Users/cltbld', 04:17:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:17:44 INFO - 'LOGNAME': 'cltbld', 04:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:44 INFO - 'MOZ_NO_REMOTE': '1', 04:17:44 INFO - 'NO_EM_RESTART': '1', 04:17:44 INFO - 'PAGER': '/bin/cat', 04:17:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:44 INFO - 'PWD': '/builds/slave/test', 04:17:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:17:44 INFO - 'SHELL': '/bin/bash', 04:17:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XXzOtccd7f/Listeners', 04:17:44 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 04:17:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:17:44 INFO - 'USER': 'cltbld', 04:17:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:17:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:44 INFO - 'XPC_FLAGS': '0x0', 04:17:44 INFO - 'XPC_SERVICE_NAME': '0', 04:17:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:17:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:44 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 04:17:44 INFO - Cleaning up... 04:17:44 INFO - Return code: 0 04:17:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:17: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')]} 04:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17: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')]} 04:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17: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', '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 0x1075c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94d357f5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a3d30>, '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 0x1072cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107398be8>, '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.SUNZAxVopV/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.XXzOtccd7f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 04:17: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', 'psutil>=3.1.1'] in /builds/slave/test/build 04:17: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 psutil>=3.1.1 04:17:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SUNZAxVopV/Render', 04:17:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:17:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:17:44 INFO - 'HOME': '/Users/cltbld', 04:17:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:17:44 INFO - 'LOGNAME': 'cltbld', 04:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:44 INFO - 'MOZ_NO_REMOTE': '1', 04:17:44 INFO - 'NO_EM_RESTART': '1', 04:17:44 INFO - 'PAGER': '/bin/cat', 04:17:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:44 INFO - 'PWD': '/builds/slave/test', 04:17:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:17:44 INFO - 'SHELL': '/bin/bash', 04:17:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XXzOtccd7f/Listeners', 04:17:44 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 04:17:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:17:44 INFO - 'USER': 'cltbld', 04:17:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:17:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:44 INFO - 'XPC_FLAGS': '0x0', 04:17:44 INFO - 'XPC_SERVICE_NAME': '0', 04:17:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:17:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:45 INFO - Downloading/unpacking psutil>=3.1.1 04:17: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 04:17: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 04:17: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 04:17: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 04:17: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 04:17: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 04:17:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:17:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:17:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:17:48 INFO - Installing collected packages: psutil 04:17:48 INFO - Running setup.py install for psutil 04:17:48 INFO - building 'psutil._psutil_osx' extension 04:17:48 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 04:17:49 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 04:17:49 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 04:17:49 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 04:17:49 INFO - building 'psutil._psutil_posix' extension 04:17:49 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 04:17:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:17:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:17:49 INFO - ^ 04:17:49 INFO - 1 warning generated. 04:17:49 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 04:17:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:17:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:17:49 INFO - Successfully installed psutil 04:17:49 INFO - Cleaning up... 04:17:49 INFO - Return code: 0 04:17:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1075c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94d357f5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a3d30>, '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 0x1072cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107398be8>, '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.SUNZAxVopV/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.XXzOtccd7f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 04:17:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:17:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:17:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SUNZAxVopV/Render', 04:17:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:17:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:17:49 INFO - 'HOME': '/Users/cltbld', 04:17:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:17:49 INFO - 'LOGNAME': 'cltbld', 04:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:49 INFO - 'MOZ_NO_REMOTE': '1', 04:17:49 INFO - 'NO_EM_RESTART': '1', 04:17:49 INFO - 'PAGER': '/bin/cat', 04:17:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:49 INFO - 'PWD': '/builds/slave/test', 04:17:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:17:49 INFO - 'SHELL': '/bin/bash', 04:17:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XXzOtccd7f/Listeners', 04:17:49 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 04:17:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:17:49 INFO - 'USER': 'cltbld', 04:17:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:17:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:49 INFO - 'XPC_FLAGS': '0x0', 04:17:49 INFO - 'XPC_SERVICE_NAME': '0', 04:17:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:17:52 INFO - Installing collected packages: mozsystemmonitor 04:17:52 INFO - Running setup.py install for mozsystemmonitor 04:17:52 INFO - Successfully installed mozsystemmonitor 04:17:52 INFO - Cleaning up... 04:17:52 INFO - Return code: 0 04:17:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:53 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 0x1075c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94d357f5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a3d30>, '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 0x1072cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107398be8>, '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.SUNZAxVopV/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.XXzOtccd7f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 04:17:53 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 04:17:53 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 04:17:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SUNZAxVopV/Render', 04:17:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:17:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:17:53 INFO - 'HOME': '/Users/cltbld', 04:17:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:17:53 INFO - 'LOGNAME': 'cltbld', 04:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:53 INFO - 'MOZ_NO_REMOTE': '1', 04:17:53 INFO - 'NO_EM_RESTART': '1', 04:17:53 INFO - 'PAGER': '/bin/cat', 04:17:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:17:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:53 INFO - 'PWD': '/builds/slave/test', 04:17:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:17:53 INFO - 'SHELL': '/bin/bash', 04:17:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XXzOtccd7f/Listeners', 04:17:53 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 04:17:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:17:53 INFO - 'USER': 'cltbld', 04:17:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:17:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:53 INFO - 'XPC_FLAGS': '0x0', 04:17:53 INFO - 'XPC_SERVICE_NAME': '0', 04:17:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:17:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:54 INFO - Downloading/unpacking jsonschema==2.5.1 04:17:54 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 04:17:54 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 04:17:54 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 04:17:54 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 04:17:54 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 04:17:54 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 04:17:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:17:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:57 INFO - Downloading functools32-3.2.3-2.tar.gz 04:17:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:17:57 INFO - warning: no files found matching '*.txt' 04:17:57 INFO - no previously-included directories found matching 'build' 04:17:57 INFO - no previously-included directories found matching 'dist' 04:17:57 INFO - no previously-included directories found matching '.git*' 04:17:57 INFO - Installing collected packages: jsonschema, functools32 04:17:57 INFO - Running setup.py install for functools32 04:17:57 INFO - warning: no files found matching '*.txt' 04:17:57 INFO - no previously-included directories found matching 'build' 04:17:57 INFO - no previously-included directories found matching 'dist' 04:17:57 INFO - no previously-included directories found matching '.git*' 04:17:57 INFO - Successfully installed jsonschema functools32 04:17:57 INFO - Cleaning up... 04:17:57 INFO - Return code: 0 04:17:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:17:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:57 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 0x1075c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94d357f5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a3d30>, '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 0x1072cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107398be8>, '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.SUNZAxVopV/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.XXzOtccd7f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 04:17:57 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 04:17:57 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 04:17:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SUNZAxVopV/Render', 04:17:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:17:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:17:57 INFO - 'HOME': '/Users/cltbld', 04:17:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:17:57 INFO - 'LOGNAME': 'cltbld', 04:17:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:57 INFO - 'MOZ_NO_REMOTE': '1', 04:17:57 INFO - 'NO_EM_RESTART': '1', 04:17:57 INFO - 'PAGER': '/bin/cat', 04:17:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:17:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:57 INFO - 'PWD': '/builds/slave/test', 04:17:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:17:57 INFO - 'SHELL': '/bin/bash', 04:17:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XXzOtccd7f/Listeners', 04:17:57 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 04:17:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:17:57 INFO - 'USER': 'cltbld', 04:17:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:17:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:17:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:57 INFO - 'XPC_FLAGS': '0x0', 04:17:57 INFO - 'XPC_SERVICE_NAME': '0', 04:17:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:17:58 INFO - Cleaning up... 04:17:58 INFO - Return code: 0 04:17:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:17:58 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')]} 04:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:58 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')]} 04:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:58 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 0x1075c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94d357f5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a3d30>, '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 0x1072cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107398be8>, '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.SUNZAxVopV/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.XXzOtccd7f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 04:17:58 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 04:17:58 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 04:17:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SUNZAxVopV/Render', 04:17:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:17:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:17:58 INFO - 'HOME': '/Users/cltbld', 04:17:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:17:58 INFO - 'LOGNAME': 'cltbld', 04:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:58 INFO - 'MOZ_NO_REMOTE': '1', 04:17:58 INFO - 'NO_EM_RESTART': '1', 04:17:58 INFO - 'PAGER': '/bin/cat', 04:17:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:58 INFO - 'PWD': '/builds/slave/test', 04:17:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:17:58 INFO - 'SHELL': '/bin/bash', 04:17:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XXzOtccd7f/Listeners', 04:17:58 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 04:17:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:17:58 INFO - 'USER': 'cltbld', 04:17:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:17:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:58 INFO - 'XPC_FLAGS': '0x0', 04:17:58 INFO - 'XPC_SERVICE_NAME': '0', 04:17:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:58 INFO - Downloading/unpacking blobuploader==1.2.4 04:17:58 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 04:17:58 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 04:17:58 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 04:17:58 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 04:17:58 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 04:17:58 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 04:18:01 INFO - Downloading blobuploader-1.2.4.tar.gz 04:18:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:18:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:18:01 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 04:18:01 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 04:18:01 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 04:18:01 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 04:18:01 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 04:18:01 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 04:18:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:18:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:18:01 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 04:18:01 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 04:18:01 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 04:18:01 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 04:18:01 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 04:18:01 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 04:18:02 INFO - Downloading docopt-0.6.1.tar.gz 04:18:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:18:02 INFO - Installing collected packages: blobuploader, requests, docopt 04:18:02 INFO - Running setup.py install for blobuploader 04:18:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:18:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:18:02 INFO - Running setup.py install for requests 04:18:02 INFO - Running setup.py install for docopt 04:18:03 INFO - Successfully installed blobuploader requests docopt 04:18:03 INFO - Cleaning up... 04:18:03 INFO - Return code: 0 04:18:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:03 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 0x1075c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94d357f5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a3d30>, '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 0x1072cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107398be8>, '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.SUNZAxVopV/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.XXzOtccd7f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 04:18:03 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 04:18:03 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 04:18:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SUNZAxVopV/Render', 04:18:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:18:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:18:03 INFO - 'HOME': '/Users/cltbld', 04:18:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:18:03 INFO - 'LOGNAME': 'cltbld', 04:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:03 INFO - 'MOZ_NO_REMOTE': '1', 04:18:03 INFO - 'NO_EM_RESTART': '1', 04:18:03 INFO - 'PAGER': '/bin/cat', 04:18:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:03 INFO - 'PWD': '/builds/slave/test', 04:18:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:18:03 INFO - 'SHELL': '/bin/bash', 04:18:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XXzOtccd7f/Listeners', 04:18:03 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 04:18:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:18:03 INFO - 'USER': 'cltbld', 04:18:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:18:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:03 INFO - 'XPC_FLAGS': '0x0', 04:18:03 INFO - 'XPC_SERVICE_NAME': '0', 04:18:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:18:03 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-bmO3jg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:18:03 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-bKzjuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:18:03 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-kwnfBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:18:03 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-f4ZQVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:18:03 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-uKZDUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:18:03 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-NzaJf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:18:04 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-G4JrTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:18:04 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-2xta3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:18:04 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-sMRjp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:18:04 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-MpxPx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:18:04 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-_2W1sR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:18:04 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-0cpwuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:18:04 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-ERnDvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:18:04 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-0xQlNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:18:04 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-cVRTyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:18:05 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-8bgADS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:18:05 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-cvgAuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:18:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:18:05 INFO - Running setup.py install for manifestparser 04:18:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:18:05 INFO - Running setup.py install for mozcrash 04:18:05 INFO - Running setup.py install for mozdebug 04:18:05 INFO - Running setup.py install for mozdevice 04:18:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:18:05 INFO - Running setup.py install for mozfile 04:18:05 INFO - Running setup.py install for mozhttpd 04:18:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:18:06 INFO - Running setup.py install for mozinfo 04:18:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:18:06 INFO - Running setup.py install for mozInstall 04:18:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:18:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:18:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:18:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:18:06 INFO - Running setup.py install for mozleak 04:18:06 INFO - Running setup.py install for mozlog 04:18:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:18:06 INFO - Running setup.py install for moznetwork 04:18:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:18:06 INFO - Running setup.py install for mozprocess 04:18:07 INFO - Running setup.py install for mozprofile 04:18:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:18:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:18:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:18:07 INFO - Running setup.py install for mozrunner 04:18:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:18:07 INFO - Running setup.py install for mozscreenshot 04:18:07 INFO - Running setup.py install for moztest 04:18:07 INFO - Running setup.py install for mozversion 04:18:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:18:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:18:07 INFO - Cleaning up... 04:18:07 INFO - Return code: 0 04:18:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:07 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 0x1075c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94d357f5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a3d30>, '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 0x1072cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107398be8>, '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.SUNZAxVopV/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.XXzOtccd7f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 04:18:07 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 04:18:07 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 04:18:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SUNZAxVopV/Render', 04:18:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:18:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:18:07 INFO - 'HOME': '/Users/cltbld', 04:18:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:18:07 INFO - 'LOGNAME': 'cltbld', 04:18:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:07 INFO - 'MOZ_NO_REMOTE': '1', 04:18:07 INFO - 'NO_EM_RESTART': '1', 04:18:07 INFO - 'PAGER': '/bin/cat', 04:18:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:18:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:07 INFO - 'PWD': '/builds/slave/test', 04:18:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:18:07 INFO - 'SHELL': '/bin/bash', 04:18:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XXzOtccd7f/Listeners', 04:18:07 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 04:18:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:18:07 INFO - 'USER': 'cltbld', 04:18:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:18:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:18:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:07 INFO - 'XPC_FLAGS': '0x0', 04:18:07 INFO - 'XPC_SERVICE_NAME': '0', 04:18:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:18:08 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-vgzh4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:18:08 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)) 04:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:18:08 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-_l7UwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:18:08 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)) 04:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:18:08 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-BWrY88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:18:08 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)) 04:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:18:08 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-AUGSsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:18:08 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)) 04:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:18:08 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-nEUPZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:18:08 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)) 04:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:18:08 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-gPxKKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:18:08 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)) 04:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:18:08 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-iTLnEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:18:08 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)) 04:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:18:08 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-3Cpo_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:18:08 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)) 04:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:18:08 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-pAXGt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:18:09 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)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:18:09 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-9mpnCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:18:09 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)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:18:09 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-eFQJtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:18:09 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)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:18:09 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-GwnDVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:18:09 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)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:18:09 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-bZCA72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:18:09 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)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:18:09 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-QX3WjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:18:09 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)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:18:09 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-kEf98l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:18:09 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)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:18:09 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-qHrf_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:18:09 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)) 04:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:18:09 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-OjcKGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:18:09 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)) 04:18:09 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)) 04:18:09 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)) 04:18:09 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)) 04:18:09 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)) 04:18:09 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)) 04:18:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:18:09 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 04:18:09 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 04:18:09 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 04:18:09 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 04:18:09 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 04:18:09 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 04:18:12 INFO - Downloading blessings-1.6.tar.gz 04:18:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:18:12 INFO - Installing collected packages: blessings 04:18:12 INFO - Running setup.py install for blessings 04:18:12 INFO - Successfully installed blessings 04:18:12 INFO - Cleaning up... 04:18:12 INFO - Return code: 0 04:18:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:18:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:18:13 INFO - Reading from file tmpfile_stdout 04:18:13 INFO - Current package versions: 04:18:13 INFO - blessings == 1.6 04:18:13 INFO - blobuploader == 1.2.4 04:18:13 INFO - docopt == 0.6.1 04:18:13 INFO - functools32 == 3.2.3-2 04:18:13 INFO - jsonschema == 2.5.1 04:18:13 INFO - manifestparser == 1.1 04:18:13 INFO - mozInstall == 1.12 04:18:13 INFO - mozcrash == 1.0 04:18:13 INFO - mozdebug == 0.1 04:18:13 INFO - mozdevice == 0.49 04:18:13 INFO - mozfile == 1.2 04:18:13 INFO - mozhttpd == 0.7 04:18:13 INFO - mozinfo == 0.9 04:18:13 INFO - mozleak == 0.1 04:18:13 INFO - mozlog == 3.4 04:18:13 INFO - moznetwork == 0.27 04:18:13 INFO - mozprocess == 0.25 04:18:13 INFO - mozprofile == 0.28 04:18:13 INFO - mozrunner == 6.13 04:18:13 INFO - mozscreenshot == 0.1 04:18:13 INFO - mozsystemmonitor == 0.3 04:18:13 INFO - moztest == 0.8 04:18:13 INFO - mozversion == 1.4 04:18:13 INFO - psutil == 3.1.1 04:18:13 INFO - requests == 1.2.3 04:18:13 INFO - wsgiref == 0.1.2 04:18:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:18:13 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')]} 04:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:13 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')]} 04:18:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:13 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 0x1075c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94d357f5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a3d30>, '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 0x1072cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107398be8>, '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.SUNZAxVopV/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.XXzOtccd7f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 04:18:13 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 04:18:13 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 04:18:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SUNZAxVopV/Render', 04:18:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:18:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:18:13 INFO - 'HOME': '/Users/cltbld', 04:18:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:18:13 INFO - 'LOGNAME': 'cltbld', 04:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:13 INFO - 'MOZ_NO_REMOTE': '1', 04:18:13 INFO - 'NO_EM_RESTART': '1', 04:18:13 INFO - 'PAGER': '/bin/cat', 04:18:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:13 INFO - 'PWD': '/builds/slave/test', 04:18:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:18:13 INFO - 'SHELL': '/bin/bash', 04:18:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XXzOtccd7f/Listeners', 04:18:13 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 04:18:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:18:13 INFO - 'USER': 'cltbld', 04:18:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:18:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:13 INFO - 'XPC_FLAGS': '0x0', 04:18:13 INFO - 'XPC_SERVICE_NAME': '0', 04:18:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:13 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)) 04:18:13 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)) 04:18:13 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)) 04:18:13 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)) 04:18:13 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)) 04:18:13 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)) 04:18:13 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)) 04:18:13 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)) 04:18:13 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)) 04:18:13 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)) 04:18:13 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)) 04:18:13 INFO - Cleaning up... 04:18:13 INFO - Return code: 0 04:18:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:18:13 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')]} 04:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:13 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')]} 04:18:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:13 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 0x1075c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94d357f5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a3d30>, '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 0x1072cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107398be8>, '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.SUNZAxVopV/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.XXzOtccd7f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 04:18:13 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 04:18:13 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 04:18:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SUNZAxVopV/Render', 04:18:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:18:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:18:13 INFO - 'HOME': '/Users/cltbld', 04:18:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:18:13 INFO - 'LOGNAME': 'cltbld', 04:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:13 INFO - 'MOZ_NO_REMOTE': '1', 04:18:13 INFO - 'NO_EM_RESTART': '1', 04:18:13 INFO - 'PAGER': '/bin/cat', 04:18:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:13 INFO - 'PWD': '/builds/slave/test', 04:18:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:18:13 INFO - 'SHELL': '/bin/bash', 04:18:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XXzOtccd7f/Listeners', 04:18:13 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 04:18:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:18:13 INFO - 'USER': 'cltbld', 04:18:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:18:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:13 INFO - 'XPC_FLAGS': '0x0', 04:18:13 INFO - 'XPC_SERVICE_NAME': '0', 04:18:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:18:13 INFO - Cleaning up... 04:18:13 INFO - Return code: 0 04:18:13 INFO - Running post-action listener: _resource_record_post_action 04:18:13 INFO - Running post-action listener: _start_resource_monitoring 04:18:13 INFO - Starting resource monitoring. 04:18:13 INFO - [mozharness: 2017-03-27 11:18:13.649967Z] Finished create-virtualenv step (success) 04:18:13 INFO - [mozharness: 2017-03-27 11:18:13.650936Z] Running install step. 04:18:13 INFO - Running pre-action listener: _resource_record_pre_action 04:18:13 INFO - Running main action method: install 04:18:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:18:13 INFO - Reading from file tmpfile_stdout 04:18:13 INFO - Detecting whether we're running mozinstall >=1.0... 04:18:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:18:14 INFO - Reading from file tmpfile_stdout 04:18:14 INFO - Output received: 04:18:14 INFO - Usage: mozinstall [options] installer 04:18:14 INFO - Options: 04:18:14 INFO - -h, --help show this help message and exit 04:18:14 INFO - -d DEST, --destination=DEST 04:18:14 INFO - Directory to install application into. [default: 04:18:14 INFO - "/builds/slave/test"] 04:18:14 INFO - --app=APP Application being installed. [default: firefox] 04:18:14 INFO - mkdir: /builds/slave/test/build/application 04:18:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:18:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:18:34 INFO - Reading from file tmpfile_stdout 04:18:34 INFO - Output received: 04:18:34 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:18:34 INFO - Running post-action listener: _resource_record_post_action 04:18:34 INFO - [mozharness: 2017-03-27 11:18:34.604203Z] Finished install step (success) 04:18:34 INFO - [mozharness: 2017-03-27 11:18:34.604310Z] Running run-tests step. 04:18:34 INFO - Running pre-action listener: _resource_record_pre_action 04:18:34 INFO - Running main action method: run_tests 04:18:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:18:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:18:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:18:34 INFO - 2017-03-27 04:18:34.617 screenresolution[1621:7329] starting screenresolution argv=screenresolution get 04:18:34 INFO - 2017-03-27 04:18:34.619 screenresolution[1621:7329] Display 0: 1600x1200x32@60 04:18:34 INFO - 2017-03-27 04:18:34.624 screenresolution[1622:7332] starting screenresolution argv=screenresolution list 04:18:34 INFO - Available Modes on Display 0 04:18:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:18:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:18:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:18:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:18:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:18:34 INFO - Intel Iris: 04:18:34 INFO - Chipset Model: Intel Iris 04:18:34 INFO - Type: GPU 04:18:34 INFO - Bus: Built-In 04:18:34 INFO - VRAM (Dynamic, Max): 1536 MB 04:18:34 INFO - Vendor: Intel (0x8086) 04:18:34 INFO - Device ID: 0x0a2e 04:18:34 INFO - Revision ID: 0x0009 04:18:34 INFO - Displays: 04:18:34 INFO - Display: 04:18:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:18:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:18:34 INFO - Display Serial Number: 1600x1200 60 04:18:34 INFO - Main Display: Yes 04:18:34 INFO - Mirror: Off 04:18:34 INFO - Online: Yes 04:18:34 INFO - Rotation: Supported 04:18:34 INFO - Return code: 0 04:18:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:18:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:18:34 INFO - Python 2.7.3 04:18:34 INFO - Return code: 0 04:18:34 INFO - grabbing minidump binary from tooltool 04:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:34 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 0x1073a3d30>, '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 0x1072cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107398be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:18:34 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 04:18:34 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 04:18:34 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 04:18:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:18:34 INFO - Return code: 0 04:18:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:18:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:18:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:18:34 INFO - ENV: RUST_BACKTRACE is now 1 04:18:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:18:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:18:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0313', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:18:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0313 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:18:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SUNZAxVopV/Render', 04:18:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:18:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:18:34 INFO - 'HOME': '/Users/cltbld', 04:18:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:18:34 INFO - 'LOGNAME': 'cltbld', 04:18:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:18:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:34 INFO - 'MOZ_NO_REMOTE': '1', 04:18:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:18:34 INFO - 'NO_EM_RESTART': '1', 04:18:34 INFO - 'PAGER': '/bin/cat', 04:18:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:34 INFO - 'PWD': '/builds/slave/test', 04:18:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:18:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:18:34 INFO - 'RUST_BACKTRACE': '1', 04:18:34 INFO - 'SHELL': '/bin/bash', 04:18:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XXzOtccd7f/Listeners', 04:18:34 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 04:18:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:18:34 INFO - 'USER': 'cltbld', 04:18:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:18:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:34 INFO - 'XPC_FLAGS': '0x0', 04:18:34 INFO - 'XPC_SERVICE_NAME': '0', 04:18:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:18:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0313', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:18:35 INFO - mozversion application_buildid: 20170327030203 04:18:35 INFO - mozversion application_changeset: cc53710589fb500610495da5258b7b9221edf681 04:18:35 INFO - mozversion application_display_name: Nightly 04:18:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:18:35 INFO - mozversion application_name: Firefox 04:18:35 INFO - mozversion application_remotingname: firefox 04:18:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:18:35 INFO - mozversion application_vendor: Mozilla 04:18:35 INFO - mozversion application_version: 55.0a1 04:18:35 INFO - mozversion platform_buildid: 20170327030203 04:18:35 INFO - mozversion platform_changeset: cc53710589fb500610495da5258b7b9221edf681 04:18:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:18:35 INFO - mozversion platform_version: 55.0a1 04:18:35 INFO - using testdate: 1490613515 04:18:35 INFO - actual date: 1490613515 04:18:35 INFO - starting webserver on 'localhost:49240' 04:18:35 INFO - SUITE-START | Running 2 tests 04:18:35 INFO - TEST-START | dromaeo_css 04:18:35 INFO - operating with platform_type : mac_ 04:18:35 INFO - Initialising browser for dromaeo_css test... 04:18:35 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/tmptOpuvd/profile http://localhost:49240/getInfo.html) 04:18:37 INFO - PID 1632 | __metrics Screen width/height:1600/1200 04:18:37 INFO - PID 1632 | colorDepth:24 04:18:37 INFO - PID 1632 | Browser inner width/height: 1024/689 04:18:37 INFO - PID 1632 | __metrics 04:18:37 ERROR - PID 1632 | 1490613517894 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:18:37 INFO - PID 1632 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:18:37 INFO - PID 1632 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:18:38 INFO - TEST-INFO | 1632: exit 0 04:18:38 INFO - Browser initialized. 04:18:38 INFO - Running cycle 1/1 for dromaeo_css test... 04:18:38 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/tmptOpuvd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:18:51 INFO - PID 1634 | RSS: Main: 233902080 04:18:51 INFO - PID 1634 | 04:21:55 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 04:21:57 INFO - PID 1634 | RSS: Main: 410374144 04:21:57 INFO - PID 1634 | 04:23:40 INFO - PID 1634 | ************************* 04:23:40 INFO - PID 1634 | A coding exception was thrown and uncaught in a Task. 04:23:40 INFO - PID 1634 | 04:23:40 ERROR - PID 1634 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:23:40 INFO - PID 1634 | Full stack: 04:23:40 INFO - PID 1634 | ************************* 04:23:40 INFO - PID 1634 | ************************* 04:23:40 INFO - PID 1634 | A coding exception was thrown and uncaught in a Task. 04:23:40 INFO - PID 1634 | 04:23:40 ERROR - PID 1634 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:23:40 INFO - PID 1634 | Full stack: 04:23:40 INFO - PID 1634 | ************************* 04:23:40 INFO - PID 1634 | ************************* 04:23:40 INFO - PID 1634 | A coding exception was thrown and uncaught in a Task. 04:23:40 INFO - PID 1634 | 04:23:40 ERROR - PID 1634 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:23:40 INFO - PID 1634 | Full stack: 04:23:40 INFO - PID 1634 | ************************* 04:23:40 INFO - PID 1634 | ************************* 04:23:40 INFO - PID 1634 | A coding exception was thrown and uncaught in a Task. 04:23:40 INFO - PID 1634 | 04:23:40 ERROR - PID 1634 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:23:40 INFO - PID 1634 | Full stack: 04:23:40 INFO - PID 1634 | ************************* 04:25:00 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 04:25:01 INFO - PID 1634 | RSS: Main: 370442240 04:25:01 INFO - PID 1634 | 04:28:03 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 04:28:04 INFO - PID 1634 | RSS: Main: 335958016 04:28:04 INFO - PID 1634 | 04:31:06 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 04:31:07 INFO - PID 1634 | RSS: Main: 332578816 04:31:07 INFO - PID 1634 | 04:34:11 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 04:34:11 INFO - PID 1634 | RSS: Main: 334860288 04:34:11 INFO - PID 1634 | 04:37:15 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 04:37:15 INFO - PID 1634 | RSS: Main: 397119488 04:37:15 INFO - PID 1634 | 04:37:15 INFO - PID 1634 | __start_tp_report 04:37:15 INFO - PID 1634 | _x_x_mozilla_page_load 04:37:15 INFO - PID 1634 | _x_x_mozilla_page_load_details 04:37:15 INFO - PID 1634 | |i|pagename|runs| 04:37:15 INFO - PID 1634 | |0;dojo.html;3095.3809238152367;3276.000819749976;3518.4639437365445;3528.2590655962244;3538.2746536959926;6102.206713127287;6130.7650488535955;6144.156752974107;6152.415520525554;6161.383861613847;5163.457836927116;6115.602485838417;6130.979101202061;6141.731496609858;6151.138840562325;5414.160805075219;5528.778848846041;5575.69333686647;5671.517920976722;5820.330661973863;5643.633163844357;5743.597948143632;5767.855803604902;5769.076947688371;5773.913391299134;5479.1781232815;5524.14375771756;5559.499645031926;5569.164625306236;5652.773889044433;5605.962896864094;5636.605437619368;5650.3219613646215;5664.745086471119;5685.374608793029;5508.118701007819;5533;5539.529140023103;5552.805651802208;5562.721863906789;6111.969440152815;6148.477379422709;6153.815385538441;6169.907451388232;6179.38206179383;6357.396047375492;6406.487481001509;6414.807555773335;6442;6532.706028228741;6647.302033286532;6717.462602991749;6718.227403848571;6732.259451460335;6740.39336459721;6156.107364432177;6202.4417802398;6215.751369945152;6216.316205217422;6249.468795152463;6601.3068627794355;6844.555103918229;6847.109875716125;6853.280405557344;6871.484638652121;6315.621062736251;6426.935730642727;6449.709763060673;6479;6538.836529086716;6624.370684784938;6693.263740988487;6702;6703.530752847254;6705.6647167642395;6581.83545411359;6593.274739778621;6596.4393026592315;6598.1422415085735;6619.801405957829;5434.5652347812875;6330.715117819709;6436.16329877113;6472.935270647234;6546.934530654633;5339.679619222859;5345.625806193529;5363.007843548956;5381.919271210935;5391.137418013099;4717.575418212377;4720.103180395714;4736.313234580966;4748.62011039123;4768.642351823627;6103;6116.082587611894;6124.716609780976;6125.111858780452;6167.167432396659;6521.184851893513;6643.800685979429;6699.196096468455;6700.798976030727;6723.899141512881;6134.04922237054;6144.324124346318;6154.876902461926;6163.506919446433;6164.321924588292;5890.52459049163;5956.166136740773;5974.074018527048;5990.31111422192;5997.490213331916;6623.437007854386;6720.764773232913;6728.226253980855;6729.764458243938;6730.629815360202;6598.439132673777;6629.3039230882;6656.400923916871;6657.567258128206;6659.766908158192;6748.392644662004;6791.388775010273;6799.626020568916;6815;6829.89755153683;6618.503612229006;6625.734970601122;6640.867182656417;6644.933550664431;6649.966750166218;6407.967960160169;6662.400383965466;6665.766698165541;6669.799906002828;6716.865662686621;6322.841928951813;6411.679416029273;6451.516136289703;6465.418112369909;6488.935110648833;5858.681796595732;6162.383761623802;6485.0596663484475;6495.447886929475;6495.707693153914;6215.720292586754;6322.620642761449;6344.809655710336;6348.968255158695;6356.586821856565;6230.7507699693315;6310.968445157745;6400.199975003125;6417.582857114273;6482.870342593216;5788.768449261982;5949.958757217556;6252.9062064069985;6264.749410023548;6274.058891166362 04:37:15 INFO - PID 1634 | |1;ext.html;778.1284960843853;799.1928152565906;1118.284298049248;1143.9599614013507;1146.1117633833783;36536.63463365369;37304.06739831502;37306.253874922484;37388.43917341242;37402.438963415574;39551.40672889909;39836;39972.8008159755;41555;41622.58377416225;6091.451769340759;8648.913510864873;8777.473351598892;8778.473291602524;8789.428687135316;6208.037754148092;7658.655360508763;8516.957415212915;8523.57382130894;8538.316934645245;8562.914370856306;8591.570421478933;8617.224449799516;8627.266682332009;8636.827263454727;8526.87209691855;8567.400281980234;8579.957100214458;8583.313334933191;8615.82768344636;8430.620622072021;8523.14768523149;8593.882795236641;8599.527026013568;8608.354373421957;38584.80707596444;38626.613733862585;38653.80673096645;38661;38794.03014924621;3026.6065411496584;3029.712177343149;3036.802607830484;3048.7560995120557;3058.8776448942017;27216.183514494598;27229.86385068062;27621.30946726328;27714.307142321402;27820.582691259646;2748.3403983044127;2788.7768978481877;2805.223795074185;2883.3656595548946;2885.5960165576835;307887;310376.99999999773;310997;311086;312465;39406.60593394087;40139;49551.50448495469;49612.50387496151;49635.25547116796;41347.96630084212;49598.25602615964;49635.25547116796;49965.75017124891;50026;14256.35846386915;14379.64050898715;14619.049761765451;14619.26903654813;14643.560693179008;11706.414679266003;11737.354445505409;11739.765204696028;11753.588624398277;11763.647090587296;13922.443102275796;13924.164550127025;13964.092333998258;13979.370928308048;14105.294735263196;5357.785688572413;5476.959377718221;5811.302643682785;5829.941700583025;5834.416558344216;915.5330781301451;960.2653969713175;994.7761753605525;1002.9498525073717;1018.5976539266948;23613.88193059058;24539;24586.016559337426;24613.63079553808;24822;965.9855102173473;1078.8327809189589;1120.4117838134882;1126.2285334545863;1130.8756036835941;10153.39079655223;12529.561465348668;12542.874571254353;12570.87429125697;12571;441056;452266;474100;476950;478082;55218.619534511156;55638.44361556332;55711;55834;55839;1453.8546145385376;1464.1507925403453;1521.0873475914584;1523.2764436892387;1529.6175956010998;23655.81720913982;23944.019217284284;24065.639015414432;24074.971401167735;24158.885726107907;39477.618283359945;39658.00854978533;39687.80936571908;39692.801535992134;39800.402993954536;425225;426088;426793;427544;427839;25848.224553263433;25931.740682592932;26129.477410451305;31189.68810311868;33533.664663353054;38898.4165237516;39291.821245362684;39437.6056239434;40133.59866401416;40216;26817.195484135507;27663.712124620717;28069.719302806712;28477.572836407056;28695.713042869305;27789.722102778713;28351;28593.14220573386;28612.998545050825;28779.712202877705;34625.82687086549;35181.29637407289;35301.646983529834;35334.46998294976;35335.64664353323;27783.861080694467;28957.85521072381;29361.11916642504;29551.704482954894;29963.700362996093;20119.59760804805;20529.2814751483;22760.430989225402;22927.88536057309;22943.655845162 04:37:15 INFO - PID 1634 | |2;jquery.html;423.0919636084373;428.85633312840196;428.90349671323975;431.5684315684315;451.5439406199739;21087.683684744723;21730.456738581524;21781.663492444353;22595.887020564875;22942.88528557359;22439.32682019538;22635.886820565916;23261.767382326172;23325.65011524824;23598.410039749015;5000.749962501878;6034.788782392618;6225.688715564226;6237.781677641284;6243.219597550306;5958.433948774859;6134.785282515113;6143.815685529441;6150.323464418932;6158.414950579688;5862.912056319158;6083.117947897552;6096.4513193812545;6111.602745821529;6173.567850250484;6092.482139018189;6121.479674227696;6149.846253843667;6151.507879369639;6159.969200154015;5345.305110335631;5403.297571315743;5452.427495113036;5461.317335333083;5472.671639701591;28024.879004839782;28081;28124.578131328046;28207.435851283066;28582.428351433064;1824.6806808808537;1993.1130646862152;2007;2100.705901173837;2106.988645450177;16092.59768505785;16638.001719896845;16765.664686706197;16843.494695159166;17447.12764361777;1619.8218195998431;1670.6575152093912;1677.7315629499221;1693.4327000454862;1697.295622316737;141434;147769.7834532483;148683;150288;153415;12341.297610716749;12497.75004499905;12505.06212034101;12574.43415046325;12599.433025513874;16476.42332518356;16491.257893394824;16492.4227652034;16529.173541322885;16532.421365252158;7684.654190561439;8614.913850861536;8902.287816974627;8949.642014319354;9108.681196158102;6412.422881940648;8135.959320203361;8416.242538171471;8559.486430814171;8573.314134869195;6664.866702666017;6675.7997260082275;7525.887111693329;7534.736284230135;7542.811429714191;2071.2543484345624;2073.139647046489;2081.9791802082086;2087.1129769847753;2253.4704344479032;815.355868863603;912.1516989200107;937.381328323317;941.580996456584;971.7813491964392;20423.608924081098;20997.95010249512;21055.789442105688;21151.048202830603;21159.25942592002;973.3673112476805;978.7195833707444;992.9453880036667;997.9201663866874;1003.8243307421318;10998.12015038795;11004;11102.555897764161;11114.999650031536;11142.387168705636;587540;591871;599170;694123;701438;97889.51055244678;134342;165508;168098;170565;1162.604714397109;1195.9222650527688;1224.2287358963974;1240.7146356338092;1245.3959829482512;20299.188032478534;21502.817345046173;21705.131794728666;21797.237096701538;21806.454838628528;57809.69479353815;58445;58458.707706461195;59151.70424147852;59931.400685994326;79760.40479190499;80758.59620701859;82192.58903705444;87745;90452.1909561792;8326.375521835766;8518.14818518168;8536.658533658585;8637.784055398666;9246.121618446237;13631.727365452438;14032.78950815759;14359;14481.565553033426;14580.708385832011;11092.667219983414;11723.965620627556;12017.339046352534;12084.274943503418;12108.697282568006;9223.538823058687;9232.353735238468;9476.47879366614;9481.52592370365;9511.857322140311;11268.774624507629;11359;11640.476178572155;11708.297502149899;11961.461734221806;12560.371981401076;12970.675733106747;13001.284929328978;13404.597862064154;13657.385417656427;11641.825372619249;11793.587224447103;11872.821907671565;12060.638180854588;12130.454129564367 04:37:15 INFO - PID 1634 | |3;mootools.html;1193.6777070191042;1252.9812052819207;1423.9644008899777;1439.1724758263995;1455.5633310006995;9088.318376121793;9448.527573621317;10728.088112510426;11041.613543525978;11046.447677616126;10708.357498550073;10810.243282970196;10810.945945270283;10830.350178989265;10849.349039057663;2066.772655007948;2443.938901527463;2479.00839664134;2486.105002199207;2489.2781093482868;1855.4155441036098;2035.307995281604;2146.0879126371306;2467.728549859514;2490.6762120924254;2432.8322405245494;2459.766322199397;2464.112919349033;2466.5560199164142;2469.1481438903547;2403.4111642647576;2410.6986626671664;2420.782129608344;2421.83047186697;2423.6728041714496;2383.1778036577352;2397.436602398435;2402.8077753779658;2412.047241339661;2424.139430502164;10838.458077096113;11102.888971110266;11105.888941110567;11113.222074454792;11130.944345278303;6233.2208473940755;6458.93541064588;6462.289148193742;6482.351764823527;6496;3781.9054523636855;3849.268638958589;4006.939895901535;4087.693422993287;4092.1201941582403;1071.244772435431;1088.7223757941674;1118.815395459748;1165.97085072873;1204.0187247393435;15514.301856416489;15701.136437495934;15731.977421467567;15748.92125539365;15768.999999999885;5540.2243685884005;5734.139879018097;5827.65034097947;5865.35481097079;5931.288245410492;12977.675558111123;13000;13011.869881301254;13021.934890325489;13034.674133146558;6333.96833015832;6500.577462464925;6508.3491650835485;6524.771632992917;6536.084948107269;5784.305883294031;5954.285485741739;6008.759649614054;6017.127516510169;6025.216721826135;4397.922508985255;4447.3329000650165;4469.486009108994;4534.886627834265;4543.000539881221;1233.2846948769693;1388.208721029024;1390.7218556288783;1404.1013751199241;1428.2358937968138;723.913130424342;734.4161391693616;752.8005078654161;764.6826566974754;790.952542847431;5840.269966254218;6419.454346380517;6616.96691516549;6635.469162467088;6696.1629796275465;490.7938665760389;564.7797359029981;570.7574280930588;584.7076461769116;586.7946218823378;3656.542932133483;3743.3449146399817;3782.149016471327;3784.508013958223;3832.137769001952;12699.492020319269;13961.720765584632;14173.149611023371;14204.502842400467;14225.85774142266;13844.30778461073;13996.510122145879;14125;14249.216293103771;14272.571822845332;9784.853227201598;10086.596536138471;10140.138088262433;10274.537645805807;10406.115480184118;5337.8131765388025;6203.503719702503;6362.713677884413;6425.164728585254;6479.449246814073;12098.879011209776;12233.938830305791;12379.195352302071;12437.813432798695;12483.56307529232;12631.747365052832;12810.487580496676;12836.16564923277;12902.354882255662;13114.213147211198;8832.072632373509;9136.08639136081;9159.313051521029;9170.174684278447;9179.219766319671;9924.503774811086;10048.54781534818;10078.395296282246;10115.544800483849;10130.341527800669;8411.957940210259;8472.771448140109;8523.659053638034;8776.824463510566;8797;8258.42191046621;8466.830663386574;8541.87187192205;8559.828803424021;8582.485050896967;7697.8845317319165;7726.884096738441;7745.883811742716;7854.4894581851995;7858.685652573833;4971.9254211186135;5717.942820571908;5773.971130144322;5972.611780234271;6149.538784591263;5888.116782482662;5888.411158884077;6001;6040.697965101639;6121.510279177744 04:37:15 INFO - PID 1634 | |4;prototype.html;341.5832684125366;391.75906817307356;399.1246918885809;406.0660480893943;406.1997864207512;5149.330587023686;5712.400197979205;6130.877382452349;6159.414855588723;6185.278617279021;5775.884482310351;5916.290045194572;5940.643561386309;6104.236970378703;6139.618585818189;4214.925194075522;4749.548792864673;4774.904501909959;4777.304057059737;4787.760611969405;3973.8410463581567;4746;4768.833090841821;4789.222251094357;4806.3511425957395;3866.326989360119;4560.315952607102;4657.641366502113;4669.509701481346;4705.952940470603;3957.9010524736823;4683.929741053887;4723.4568024677255;4733.881652958669;4740.928886066712;3659.249853779969;4171.20747058061;4195.223883581517;4204.5375008749015;4209.684273679486;4978.6763860348965;4983.950160498385;5909.586328956974;5987.9101813472835;6053.909191362133;5462;5474.671519708791;5493.7802487900435;5520.420355862657;5522.91715624266;6515.837104072436;6518.869622607569;6532.856750068719;6545.836354091138;6563.310852360529;4087.591240875918;4301.397804307399;4319.740815551046;4332.981749288914;4346.978265108686;5635.746391412396;6634.23706273775;6726.587416642462;6732.865342693168;6844.1444819397575;3751.249750050001;4341.435613370241;4395.692301538926;4398.846040388571;4418.867433976986;4212.53662097169;4369.97305633176;4463.62059224963;4471.731696098244;4481.663875209372;4437.866863994085;5166.070107380708;5166.199239117943;5171.32772739549;5242.082635538765;6425.357464253537;6472.792684754947;6477.805665830033;6489.935100649028;6502.804915852532;1454.2365258239508;1508.6379268975368;1511.3727802961648;1541.3603354607712;1542.1364036139798;864.6195673903462;940.7271891151627;974.8196583631909;986.7237173591406;997.8403455447238;5162.535371816554;5595.608307418523;5897.026990546512;5937.842120786405;6077.574569780073;5551.2228288039705;5846.6784326862435;6159.6612186329285;6641.302663220292;6645.401913827779;5288.524032837063;5794.246747922743;5854.015971799335;5934.258217722785;5995.640261584319;2778.8054836161273;2886.480433521986;3430.3139372125675;3448.534372891511;3449.896503104911;6205.28639206488;6398.680065996681;6643.701033453506;6768.9661551691925;6807.455403567703;6796.728130874809;6806.93193068063;6808.2170550386345;6819;6828.283030281749;6383.904241436289;6753.358430948953;6781.796546103625;6807.693653785592;6852.588844669336;5576.693281869536;6242.969909964805;6458.935410645833;6620.768273110418;6667.566608170648;6354.4916406686425;6513.739450422119;6558.934410655832;6560.507961902781;6611.768588099393;5575.637583557056;5678.990135228336;5771.94228057714;6211.782587609412;6779.762708305186;3700.27844570317;3923.450716899579;4305.31115021595;4374.387585738063;4378.671599629977;6154.353792851686;6197.73782569368;6446.194225721785;6579.4736421087155;6669.332267093007;4925.064237794808;5277.2004157006895;5635.267415235993;5748.338941021836;6516.5462304976545;6512.185976752906;6528.151340325728;6534.901976470262;6563.868722625616;6569.868602628017;5118.566801295643;6378.394052564999;6410.4871610272;6417.133686952202;6425;6528.836779080561;6532.477401807749;6546.574472659262;6558.80323590293;6571.967140164268;6130.4060944155735;6344.936550634619;6392.872142557216;6415.903761443488;6441.838954026187 04:37:15 INFO - PID 1634 | |5;yui.html;634.8888944434724;676.6244734172532;713.1442269276865;734.7758933525273;735.1509007096805;14913.850861491383;15231.086134831918;15780.684386312267;17387.478375648734;18293.17680704368;16711.582210444743;16775.161241937883;16847.157642117905;17221.7416738749;17492.825071749277;962.7641227899157;976.3604838830578;977.5258999385293;978.33473238198;978.8042391521689;944.7354740672623;965.6909788867565;974.0454354732367;978;980.7499087732654;1021.7445638590352;1022.3661329975425;1023.2376879224988;1023.3399457350005;1030.634124885666;1012.6658202793061;1014.9951547967459;1016.9644062457816;1022.4785359466651;1027.342500799489;754.9924500754977;760.638696619107;760.8288135169598;763.7288762489347;772.7874834420523;16135.435259765853;16227.513174604663;16576.83423165765;16591.917040414843;16874.578135546588;14659.92670036643;14684.779728304084;14700.779488307684;14809.407623695039;14826.110433374031;11534.884651153465;11649.883501164964;11723.7069073273;11917.761644767055;12212.694682633006;3000.6249218847643;3027.3928295972023;3060.693930606944;3136.5138403547485;3167.984160079208;100312;104552.47723761409;105257.47371263172;105276;107526.99999999921;10381.84427233592;10878.945605271923;11263.774724505463;11467.254628449124;11677.474513646906;24338.756612434005;24828.875855620605;24869.140048894245;24977;25025.499490010097;7548.169701332849;7590.8861367079535;7604.619769011527;7690.692372305157;7833.686652533949;4106.958930410658;4166.437530933345;4211.389348544444;4225.746455212697;4229.238737027365;4145.17096580685;4236.872893813191;4251.617354438054;4252.638525725348;4256.9996050928;841.4993079118037;866.3978534918172;866.9479831210148;880.9691660791841;888.7733627924838;533.5651444073068;546.5900574569073;576.7865889620867;580.4253788749108;631.8767840271195;8014.959925200454;10002.349847259904;10109.595616175418;10366.481675916324;10665.520051597698;523.1890569617078;546.7047794191171;567.9772809087673;577.1804038265673;600.7446835095067;4542.818287268472;4765.428148622118;4766.94810400977;4815.662903596714;4837.830675926326;90484.09515904756;95561.04438955522;95621;96741.51629241809;98535;73333.63333183406;78894.2110578887;81553.59223203965;83635.327293455;83886;72600.63699681468;73228.63385683038;73685;73812;74712.62643686747;9135.58889849945;9262.814743704954;9516.85724714116;9561.235101191749;9595.904040959502;36286.818565907;36782;37057.88826335214;37290.62709372872;37825;39190.608093918694;48538;49600.751996239786;49874.750626246634;50386.74806625943;11431.771364572496;11490.681683520608;11548.480318385526;11688.415579221175;12133.39333033327;11501.482433290355;12420.565280215149;12489.93755031219;12645.367731613567;12782.296973666165;11593.304401735923;11642.70893227676;11844.644660660195;12027.819582706092;12196.207246528948;11684.637530748485;11721;12190.695232619255;12197.207181533173;12342.938285308515;11464;11685.532578696757;12171.634850954486;12193;12335;7899.881501777593;8689.261412779731;8845;8881.245094167069;9155.450672959643;8243.958780206061;8670.566471676517;9751.3661611995;9762.072603102693;9878.4566848824 04:37:15 INFO - PID 1634 | __end_tp_report 04:37:15 INFO - PID 1634 | __start_cc_report 04:37:15 INFO - PID 1634 | _x_x_mozilla_cycle_collect,2101 04:37:15 INFO - PID 1634 | __end_cc_report 04:37:15 INFO - PID 1634 | __startTimestamp1490614635421__endTimestamp 04:37:15 INFO - PID 1634 | 04:37:15 INFO - PID 1634 | ------- Summary: start ------- 04:37:15 INFO - PID 1634 | Number of tests: 6 04:37:15 INFO - PID 1634 | 04:37:15 INFO - PID 1634 | [#0] dojo.html Cycles:165 Average:6113.88 Median:6252.91 stddev:687.79 (11.0%) stddev-sans-first:647.86 04:37:15 INFO - PID 1634 | Values: 3095.4 3276.0 3518.5 3528.3 3538.3 6102.2 6130.8 6144.2 6152.4 6161.4 5163.5 6115.6 6131.0 6141.7 6151.1 5414.2 5528.8 5575.7 5671.5 5820.3 5643.6 5743.6 5767.9 5769.1 5773.9 5479.2 5524.1 5559.5 5569.2 5652.8 5606.0 5636.6 5650.3 5664.7 5685.4 5508.1 5533.0 5539.5 5552.8 5562.7 6112.0 6148.5 6153.8 6169.9 6179.4 6357.4 6406.5 6414.8 6442.0 6532.7 6647.3 6717.5 6718.2 6732.3 6740.4 6156.1 6202.4 6215.8 6216.3 6249.5 6601.3 6844.6 6847.1 6853.3 6871.5 6315.6 6426.9 6449.7 6479.0 6538.8 6624.4 6693.3 6702.0 6703.5 6705.7 6581.8 6593.3 6596.4 6598.1 6619.8 5434.6 6330.7 6436.2 6472.9 6546.9 5339.7 5345.6 5363.0 5381.9 5391.1 4717.6 4720.1 4736.3 4748.6 4768.6 6103.0 6116.1 6124.7 6125.1 6167.2 6521.2 6643.8 6699.2 6700.8 6723.9 6134.0 6144.3 6154.9 6163.5 6164.3 5890.5 5956.2 5974.1 5990.3 5997.5 6623.4 6720.8 6728.2 6729.8 6730.6 6598.4 6629.3 6656.4 6657.6 6659.8 6748.4 6791.4 6799.6 6815.0 6829.9 6618.5 6625.7 6640.9 6644.9 6650.0 6408.0 6662.4 6665.8 6669.8 6716.9 6322.8 6411.7 6451.5 6465.4 6488.9 5858.7 6162.4 6485.1 6495.4 6495.7 6215.7 6322.6 6344.8 6349.0 6356.6 6230.8 6311.0 6400.2 6417.6 6482.9 5788.8 5950.0 6252.9 6264.7 6274.1 04:37:15 INFO - PID 1634 | 04:37:15 INFO - PID 1634 | [#1] ext.html Cycles:180 Average:52888.52 Median:35241.47 stddev:107926.09 (306.2%) stddev-sans-first:108157.94 04:37:15 INFO - PID 1634 | Values: 778.1 799.2 1118.3 1144.0 1146.1 36536.6 37304.1 37306.3 37388.4 37402.4 39551.4 39836.0 39972.8 41555.0 41622.6 6091.5 8648.9 8777.5 8778.5 8789.4 6208.0 7658.7 8517.0 8523.6 8538.3 8562.9 8591.6 8617.2 8627.3 8636.8 8526.9 8567.4 8580.0 8583.3 8615.8 8430.6 8523.1 8593.9 8599.5 8608.4 38584.8 38626.6 38653.8 38661.0 38794.0 3026.6 3029.7 3036.8 3048.8 3058.9 27216.2 27229.9 27621.3 27714.3 27820.6 2748.3 2788.8 2805.2 2883.4 2885.6 307887.0 310377.0 310997.0 311086.0 312465.0 39406.6 40139.0 49551.5 49612.5 49635.3 41348.0 49598.3 49635.3 49965.8 50026.0 14256.4 14379.6 14619.0 14619.3 14643.6 11706.4 11737.4 11739.8 11753.6 11763.6 13922.4 13924.2 13964.1 13979.4 14105.3 5357.8 5477.0 5811.3 5829.9 5834.4 915.5 960.3 994.8 1002.9 1018.6 23613.9 24539.0 24586.0 24613.6 24822.0 966.0 1078.8 1120.4 1126.2 1130.9 10153.4 12529.6 12542.9 12570.9 12571.0 441056.0 452266.0 474100.0 476950.0 478082.0 55218.6 55638.4 55711.0 55834.0 55839.0 1453.9 1464.2 1521.1 1523.3 1529.6 23655.8 23944.0 24065.6 24075.0 24158.9 39477.6 39658.0 39687.8 39692.8 39800.4 425225.0 426088.0 426793.0 427544.0 427839.0 25848.2 25931.7 26129.5 31189.7 33533.7 38898.4 39291.8 39437.6 40133.6 40216.0 26817.2 27663.7 28069.7 28477.6 28695.7 27789.7 28351.0 28593.1 28613.0 28779.7 34625.8 35181.3 35301.6 35334.5 35335.6 27783.9 28957.9 29361.1 29551.7 29963.7 20119.6 20529.3 22760.4 22927.9 22943.7 04:37:15 INFO - PID 1634 | 04:37:15 INFO - PID 1634 | [#2] jquery.html Cycles:180 Average:38595.14 Median:22789.39 stddev:107273.07 (470.7%) stddev-sans-first:107535.70 04:37:15 INFO - PID 1634 | Values: 423.1 428.9 428.9 431.6 451.5 21087.7 21730.5 21781.7 22595.9 22942.9 22439.3 22635.9 23261.8 23325.7 23598.4 5000.7 6034.8 6225.7 6237.8 6243.2 5958.4 6134.8 6143.8 6150.3 6158.4 5862.9 6083.1 6096.5 6111.6 6173.6 6092.5 6121.5 6149.8 6151.5 6160.0 5345.3 5403.3 5452.4 5461.3 5472.7 28024.9 28081.0 28124.6 28207.4 28582.4 1824.7 1993.1 2007.0 2100.7 2107.0 16092.6 16638.0 16765.7 16843.5 17447.1 1619.8 1670.7 1677.7 1693.4 1697.3 141434.0 147769.8 148683.0 150288.0 153415.0 12341.3 12497.8 12505.1 12574.4 12599.4 16476.4 16491.3 16492.4 16529.2 16532.4 7684.7 8614.9 8902.3 8949.6 9108.7 6412.4 8136.0 8416.2 8559.5 8573.3 6664.9 6675.8 7525.9 7534.7 7542.8 2071.3 2073.1 2082.0 2087.1 2253.5 815.4 912.2 937.4 941.6 971.8 20423.6 20998.0 21055.8 21151.0 21159.3 973.4 978.7 992.9 997.9 1003.8 10998.1 11004.0 11102.6 11115.0 11142.4 587540.0 591871.0 599170.0 694123.0 701438.0 97889.5 134342.0 165508.0 168098.0 170565.0 1162.6 1195.9 1224.2 1240.7 1245.4 20299.2 21502.8 21705.1 21797.2 21806.5 57809.7 58445.0 58458.7 59151.7 59931.4 79760.4 80758.6 82192.6 87745.0 90452.2 8326.4 8518.1 8536.7 8637.8 9246.1 13631.7 14032.8 14359.0 14481.6 14580.7 11092.7 11724.0 12017.3 12084.3 12108.7 9223.5 9232.4 9476.5 9481.5 9511.9 11268.8 11359.0 11640.5 11708.3 11961.5 12560.4 12970.7 13001.3 13404.6 13657.4 11641.8 11793.6 11872.8 12060.6 12130.5 04:37:15 INFO - PID 1634 | 04:37:15 INFO - PID 1634 | [#3] mootools.html Cycles:180 Average:6841.89 Median:3073.61 stddev:4299.46 (139.9%) stddev-sans-first:4290.57 04:37:15 INFO - PID 1634 | Values: 1193.7 1253.0 1424.0 1439.2 1455.6 9088.3 9448.5 10728.1 11041.6 11046.4 10708.4 10810.2 10810.9 10830.4 10849.3 2066.8 2443.9 2479.0 2486.1 2489.3 1855.4 2035.3 2146.1 2467.7 2490.7 2432.8 2459.8 2464.1 2466.6 2469.1 2403.4 2410.7 2420.8 2421.8 2423.7 2383.2 2397.4 2402.8 2412.0 2424.1 10838.5 11102.9 11105.9 11113.2 11130.9 6233.2 6458.9 6462.3 6482.4 6496.0 3781.9 3849.3 4006.9 4087.7 4092.1 1071.2 1088.7 1118.8 1166.0 1204.0 15514.3 15701.1 15732.0 15748.9 15769.0 5540.2 5734.1 5827.7 5865.4 5931.3 12977.7 13000.0 13011.9 13021.9 13034.7 6334.0 6500.6 6508.3 6524.8 6536.1 5784.3 5954.3 6008.8 6017.1 6025.2 4397.9 4447.3 4469.5 4534.9 4543.0 1233.3 1388.2 1390.7 1404.1 1428.2 723.9 734.4 752.8 764.7 791.0 5840.3 6419.5 6617.0 6635.5 6696.2 490.8 564.8 570.8 584.7 586.8 3656.5 3743.3 3782.1 3784.5 3832.1 12699.5 13961.7 14173.1 14204.5 14225.9 13844.3 13996.5 14125.0 14249.2 14272.6 9784.9 10086.6 10140.1 10274.5 10406.1 5337.8 6203.5 6362.7 6425.2 6479.4 12098.9 12233.9 12379.2 12437.8 12483.6 12631.7 12810.5 12836.2 12902.4 13114.2 8832.1 9136.1 9159.3 9170.2 9179.2 9924.5 10048.5 10078.4 10115.5 10130.3 8412.0 8472.8 8523.7 8776.8 8797.0 8258.4 8466.8 8541.9 8559.8 8582.5 7697.9 7726.9 7745.9 7854.5 7858.7 4971.9 5717.9 5774.0 5972.6 6149.5 5888.1 5888.4 6001.0 6040.7 6121.5 04:37:15 INFO - PID 1634 | 04:37:15 INFO - PID 1634 | [#4] prototype.html Cycles:180 Average:5201.51 Median:5850.35 stddev:1617.64 (27.7%) stddev-sans-first:1580.51 04:37:15 INFO - PID 1634 | Values: 341.6 391.8 399.1 406.1 406.2 5149.3 5712.4 6130.9 6159.4 6185.3 5775.9 5916.3 5940.6 6104.2 6139.6 4214.9 4749.5 4774.9 4777.3 4787.8 3973.8 4746.0 4768.8 4789.2 4806.4 3866.3 4560.3 4657.6 4669.5 4706.0 3957.9 4683.9 4723.5 4733.9 4740.9 3659.2 4171.2 4195.2 4204.5 4209.7 4978.7 4984.0 5909.6 5987.9 6053.9 5462.0 5474.7 5493.8 5520.4 5522.9 6515.8 6518.9 6532.9 6545.8 6563.3 4087.6 4301.4 4319.7 4333.0 4347.0 5635.7 6634.2 6726.6 6732.9 6844.1 3751.2 4341.4 4395.7 4398.8 4418.9 4212.5 4370.0 4463.6 4471.7 4481.7 4437.9 5166.1 5166.2 5171.3 5242.1 6425.4 6472.8 6477.8 6489.9 6502.8 1454.2 1508.6 1511.4 1541.4 1542.1 864.6 940.7 974.8 986.7 997.8 5162.5 5595.6 5897.0 5937.8 6077.6 5551.2 5846.7 6159.7 6641.3 6645.4 5288.5 5794.2 5854.0 5934.3 5995.6 2778.8 2886.5 3430.3 3448.5 3449.9 6205.3 6398.7 6643.7 6769.0 6807.5 6796.7 6806.9 6808.2 6819.0 6828.3 6383.9 6753.4 6781.8 6807.7 6852.6 5576.7 6243.0 6458.9 6620.8 6667.6 6354.5 6513.7 6558.9 6560.5 6611.8 5575.6 5679.0 5771.9 6211.8 6779.8 3700.3 3923.5 4305.3 4374.4 4378.7 6154.4 6197.7 6446.2 6579.5 6669.3 4925.1 5277.2 5635.3 5748.3 6516.5 6512.2 6528.2 6534.9 6563.9 6569.9 5118.6 6378.4 6410.5 6417.1 6425.0 6528.8 6532.5 6546.6 6558.8 6572.0 6130.4 6344.9 6392.9 6415.9 6441.8 04:37:15 INFO - PID 1634 | 04:37:15 INFO - PID 1634 | [#5] yui.html Cycles:180 Average:18865.03 Median:4126.06 stddev:26890.00 (651.7%) stddev-sans-first:26930.60 04:37:15 INFO - PID 1634 | Values: 634.9 676.6 713.1 734.8 735.2 14913.9 15231.1 15780.7 17387.5 18293.2 16711.6 16775.2 16847.2 17221.7 17492.8 962.8 976.4 977.5 978.3 978.8 944.7 965.7 974.0 978.0 980.7 1021.7 1022.4 1023.2 1023.3 1030.6 1012.7 1015.0 1017.0 1022.5 1027.3 755.0 760.6 760.8 763.7 772.8 16135.4 16227.5 16576.8 16591.9 16874.6 14659.9 14684.8 14700.8 14809.4 14826.1 11534.9 11649.9 11723.7 11917.8 12212.7 3000.6 3027.4 3060.7 3136.5 3168.0 100312.0 104552.5 105257.5 105276.0 107527.0 10381.8 10878.9 11263.8 11467.3 11677.5 24338.8 24828.9 24869.1 24977.0 25025.5 7548.2 7590.9 7604.6 7690.7 7833.7 4107.0 4166.4 4211.4 4225.7 4229.2 4145.2 4236.9 4251.6 4252.6 4257.0 841.5 866.4 866.9 881.0 888.8 533.6 546.6 576.8 580.4 631.9 8015.0 10002.3 10109.6 10366.5 10665.5 523.2 546.7 568.0 577.2 600.7 4542.8 4765.4 4766.9 4815.7 4837.8 90484.1 95561.0 95621.0 96741.5 98535.0 73333.6 78894.2 81553.6 83635.3 83886.0 72600.6 73228.6 73685.0 73812.0 74712.6 9135.6 9262.8 9516.9 9561.2 9595.9 36286.8 36782.0 37057.9 37290.6 37825.0 39190.6 48538.0 49600.8 49874.8 50386.7 11431.8 11490.7 11548.5 11688.4 12133.4 11501.5 12420.6 12489.9 12645.4 12782.3 11593.3 11642.7 11844.6 12027.8 12196.2 11684.6 11721.0 12190.7 12197.2 12342.9 11464.0 11685.5 12171.6 12193.0 12335.0 7899.9 8689.3 8845.0 8881.2 9155.5 8244.0 8670.6 9751.4 9762.1 9878.5 04:37:15 INFO - PID 1634 | -------- Summary: end -------- 04:37:15 INFO - PID 1634 | 04:37:16 INFO - TEST-INFO | 1634: exit 0 04:37:16 INFO - TEST-OK | dromaeo_css | took 1120917ms 04:37:16 INFO - TEST-START | kraken 04:37:16 INFO - operating with platform_type : mac_ 04:37:16 INFO - Initialising browser for kraken test... 04:37:16 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/tmpEifYwh/profile http://localhost:49240/getInfo.html) 04:37:18 INFO - PID 1645 | __metrics Screen width/height:1600/1200 04:37:18 INFO - PID 1645 | colorDepth:24 04:37:18 INFO - PID 1645 | Browser inner width/height: 1024/689 04:37:18 INFO - PID 1645 | __metrics 04:37:18 ERROR - PID 1645 | 1490614638665 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:37:18 INFO - PID 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:37:18 INFO - PID 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:37:19 INFO - TEST-INFO | 1645: exit 0 04:37:19 INFO - Browser initialized. 04:37:19 INFO - Running cycle 1/1 for kraken test... 04:37:19 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/tmpEifYwh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:37:31 INFO - PID 1647 | RSS: Main: 234094592 04:37:31 INFO - PID 1647 | 04:37:34 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 04:37:35 INFO - PID 1647 | RSS: Main: 286150656 04:37:35 INFO - PID 1647 | 04:37:37 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 04:37:38 INFO - PID 1647 | RSS: Main: 289255424 04:37:38 INFO - PID 1647 | 04:37:42 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 04:37:42 INFO - PID 1647 | RSS: Main: 379641856 04:37:42 INFO - PID 1647 | 04:37:44 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 04:37:45 INFO - PID 1647 | RSS: Main: 281399296 04:37:45 INFO - PID 1647 | 04:37:47 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:37:48 INFO - PID 1647 | RSS: Main: 284889088 04:37:48 INFO - PID 1647 | 04:37:51 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 04:37:52 INFO - PID 1647 | RSS: Main: 325910528 04:37:52 INFO - PID 1647 | 04:37:55 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 04:37:55 INFO - PID 1647 | RSS: Main: 339009536 04:37:55 INFO - PID 1647 | 04:37:58 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 04:37:59 INFO - PID 1647 | RSS: Main: 348450816 04:37:59 INFO - PID 1647 | 04:38:01 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:38:01 INFO - PID 1647 | RSS: Main: 302415872 04:38:01 INFO - PID 1647 | 04:38:03 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:38:04 INFO - PID 1647 | RSS: Main: 306429952 04:38:04 INFO - PID 1647 | 04:38:06 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:38:07 INFO - PID 1647 | RSS: Main: 309063680 04:38:07 INFO - PID 1647 | 04:38:10 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:38:10 INFO - PID 1647 | RSS: Main: 325246976 04:38:10 INFO - PID 1647 | 04:38:14 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:38:14 INFO - PID 1647 | RSS: Main: 318320640 04:38:14 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 04:38:16 INFO - PID 1647 | RSS: Main: 380071936 04:38:16 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | __start_tp_report 04:38:16 INFO - PID 1647 | _x_x_mozilla_page_load 04:38:16 INFO - PID 1647 | _x_x_mozilla_page_load_details 04:38:16 INFO - PID 1647 | |i|pagename|runs| 04:38:16 INFO - PID 1647 | |0;ai-astar;112;112;112;112;111;113;156;159;112;112 04:38:16 INFO - PID 1647 | |1;audio-beat-detection;141;113;110;149;110;110;110;109;133;105 04:38:16 INFO - PID 1647 | |2;audio-dft;199;256;200;190;257;203;182;183;185;183 04:38:16 INFO - PID 1647 | |3;audio-fft;79;60;61;89;61;58;61;60;77;63 04:38:16 INFO - PID 1647 | |4;audio-oscillator;99;96;117;98;71;70;84;69;68;85 04:38:16 INFO - PID 1647 | |5;imaging-gaussian-blur;89;96;131;85;123;97;116;131;131;88 04:38:16 INFO - PID 1647 | |6;imaging-darkroom;96;96;96;95;95;96;97;102;95;96 04:38:16 INFO - PID 1647 | |7;imaging-desaturate;86;93;137;136;86;83;84;83;109;129 04:38:16 INFO - PID 1647 | |8;json-parse-financial;64;60;60;61;61;63;61;61;61;62 04:38:16 INFO - PID 1647 | |9;json-stringify-tinderbox;53;54;65;56;62;63;57;59;56;57 04:38:16 INFO - PID 1647 | |10;stanford-crypto-aes;82;96;101;80;83;89;100;99;102;84 04:38:16 INFO - PID 1647 | |11;stanford-crypto-ccm;146;144;131;137;137;139;136;137;187;159 04:38:16 INFO - PID 1647 | |12;stanford-crypto-pbkdf2;189;185;186;188;188;219;199;188;184;184 04:38:16 INFO - PID 1647 | |13;stanford-crypto-sha256-iterative;68;68;67;66;68;69;68;68;68;68 04:38:16 INFO - PID 1647 | __end_tp_report 04:38:16 INFO - PID 1647 | __start_cc_report 04:38:16 INFO - PID 1647 | _x_x_mozilla_cycle_collect,2149 04:38:16 INFO - PID 1647 | __end_cc_report 04:38:16 INFO - PID 1647 | __startTimestamp1490614696719__endTimestamp 04:38:16 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | ------- Summary: start ------- 04:38:16 INFO - PID 1647 | Number of tests: 14 04:38:16 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | [#0] ai-astar Cycles:10 Average:121.10 Median:112.00 stddev:19.20 (17.1%) stddev-sans-first:20.08 04:38:16 INFO - PID 1647 | Values: 112.0 112.0 112.0 112.0 111.0 113.0 156.0 159.0 112.0 112.0 04:38:16 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | [#1] audio-beat-detection Cycles:10 Average:119.00 Median:111.50 stddev:15.76 (14.1%) stddev-sans-first:14.57 04:38:16 INFO - PID 1647 | Values: 141.0 113.0 110.0 149.0 110.0 110.0 110.0 109.0 133.0 105.0 04:38:16 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | [#2] audio-dft Cycles:10 Average:203.80 Median:199.50 stddev:28.82 (14.4%) stddev-sans-first:30.52 04:38:16 INFO - PID 1647 | Values: 199.0 256.0 200.0 190.0 257.0 203.0 182.0 183.0 185.0 183.0 04:38:16 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | [#3] audio-fft Cycles:10 Average:66.90 Median:62.00 stddev:10.70 (17.3%) stddev-sans-first:10.42 04:38:16 INFO - PID 1647 | Values: 79.0 60.0 61.0 89.0 61.0 58.0 61.0 60.0 77.0 63.0 04:38:16 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | [#4] audio-oscillator Cycles:10 Average:85.70 Median:84.50 stddev:16.57 (19.6%) stddev-sans-first:16.87 04:38:16 INFO - PID 1647 | Values: 99.0 96.0 117.0 98.0 71.0 70.0 84.0 69.0 68.0 85.0 04:38:16 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:108.70 Median:86.50 stddev:19.51 (22.6%) stddev-sans-first:19.35 04:38:16 INFO - PID 1647 | Values: 89.0 96.0 131.0 85.0 123.0 97.0 116.0 131.0 131.0 88.0 04:38:16 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | [#6] imaging-darkroom Cycles:10 Average:96.40 Median:96.00 stddev:2.07 (2.2%) stddev-sans-first:2.19 04:38:16 INFO - PID 1647 | Values: 96.0 96.0 96.0 95.0 95.0 96.0 97.0 102.0 95.0 96.0 04:38:16 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | [#7] imaging-desaturate Cycles:10 Average:102.60 Median:83.50 stddev:23.08 (27.6%) stddev-sans-first:23.69 04:38:16 INFO - PID 1647 | Values: 86.0 93.0 137.0 136.0 86.0 83.0 84.0 83.0 109.0 129.0 04:38:16 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:61.00 stddev:1.26 (2.1%) stddev-sans-first:0.93 04:38:16 INFO - PID 1647 | Values: 64.0 60.0 60.0 61.0 61.0 63.0 61.0 61.0 61.0 62.0 04:38:16 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:58.20 Median:58.00 stddev:3.97 (6.8%) stddev-sans-first:3.73 04:38:16 INFO - PID 1647 | Values: 53.0 54.0 65.0 56.0 62.0 63.0 57.0 59.0 56.0 57.0 04:38:16 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:83.50 stddev:8.86 (10.6%) stddev-sans-first:8.69 04:38:16 INFO - PID 1647 | Values: 82.0 96.0 101.0 80.0 83.0 89.0 100.0 99.0 102.0 84.0 04:38:16 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:141.50 stddev:16.55 (11.7%) stddev-sans-first:17.56 04:38:16 INFO - PID 1647 | Values: 146.0 144.0 131.0 137.0 137.0 139.0 136.0 137.0 187.0 159.0 04:38:16 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.00 Median:188.00 stddev:10.74 (5.7%) stddev-sans-first:11.37 04:38:16 INFO - PID 1647 | Values: 189.0 185.0 186.0 188.0 188.0 219.0 199.0 188.0 184.0 184.0 04:38:16 INFO - PID 1647 | 04:38:16 INFO - PID 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:0.79 (1.2%) stddev-sans-first:0.83 04:38:16 INFO - PID 1647 | Values: 68.0 68.0 67.0 66.0 68.0 69.0 68.0 68.0 68.0 68.0 04:38:16 INFO - PID 1647 | -------- Summary: end -------- 04:38:16 INFO - PID 1647 | 04:38:17 INFO - TEST-INFO | 1647: exit 0 04:38:17 INFO - TEST-OK | kraken | took 61667ms 04:38:17 INFO - SUITE-END | took 1182s 04:38:17 INFO - Completed test suite (00:19:42) 04:38:17 INFO - javascript benchmark 04:38:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7613.2199773607235, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3095.3809238152367, 3276.000819749976, 3518.4639437365445, 3528.2590655962244, 3538.2746536959926, 6102.206713127287, 6130.7650488535955, 6144.156752974107, 6152.415520525554, 6161.383861613847, 5163.457836927116, 6115.602485838417, 6130.979101202061, 6141.731496609858, 6151.138840562325, 5414.160805075219, 5528.778848846041, 5575.69333686647, 5671.517920976722, 5820.330661973863, 5643.633163844357, 5743.597948143632, 5767.855803604902, 5769.076947688371, 5773.913391299134, 5479.1781232815, 5524.14375771756, 5559.499645031926, 5569.164625306236, 5652.773889044433, 5605.962896864094, 5636.605437619368, 5650.3219613646215, 5664.745086471119, 5685.374608793029, 5508.118701007819, 5533.0, 5539.529140023103, 5552.805651802208, 5562.721863906789, 6111.969440152815, 6148.477379422709, 6153.815385538441, 6169.907451388232, 6179.38206179383, 6357.396047375492, 6406.487481001509, 6414.807555773335, 6442.0, 6532.706028228741, 6647.302033286532, 6717.462602991749, 6718.227403848571, 6732.259451460335, 6740.39336459721, 6156.107364432177, 6202.4417802398, 6215.751369945152, 6216.316205217422, 6249.468795152463, 6601.3068627794355, 6844.555103918229, 6847.109875716125, 6853.280405557344, 6871.484638652121, 6315.621062736251, 6426.935730642727, 6449.709763060673, 6479.0, 6538.836529086716, 6624.370684784938, 6693.263740988487, 6702.0, 6703.530752847254, 6705.6647167642395, 6581.83545411359, 6593.274739778621, 6596.4393026592315, 6598.1422415085735, 6619.801405957829, 5434.5652347812875, 6330.715117819709, 6436.16329877113, 6472.935270647234, 6546.934530654633, 5339.679619222859, 5345.625806193529, 5363.007843548956, 5381.919271210935, 5391.137418013099, 4717.575418212377, 4720.103180395714, 4736.313234580966, 4748.62011039123, 4768.642351823627, 6103.0, 6116.082587611894, 6124.716609780976, 6125.111858780452, 6167.167432396659, 6521.184851893513, 6643.800685979429, 6699.196096468455, 6700.798976030727, 6723.899141512881, 6134.04922237054, 6144.324124346318, 6154.876902461926, 6163.506919446433, 6164.321924588292, 5890.52459049163, 5956.166136740773, 5974.074018527048, 5990.31111422192, 5997.490213331916, 6623.437007854386, 6720.764773232913, 6728.226253980855, 6729.764458243938, 6730.629815360202, 6598.439132673777, 6629.3039230882, 6656.400923916871, 6657.567258128206, 6659.766908158192, 6748.392644662004, 6791.388775010273, 6799.626020568916, 6815.0, 6829.89755153683, 6618.503612229006, 6625.734970601122, 6640.867182656417, 6644.933550664431, 6649.966750166218, 6407.967960160169, 6662.400383965466, 6665.766698165541, 6669.799906002828, 6716.865662686621, 6322.841928951813, 6411.679416029273, 6451.516136289703, 6465.418112369909, 6488.935110648833, 5858.681796595732, 6162.383761623802, 6485.0596663484475, 6495.447886929475, 6495.707693153914, 6215.720292586754, 6322.620642761449, 6344.809655710336, 6348.968255158695, 6356.586821856565, 6230.7507699693315, 6310.968445157745, 6400.199975003125, 6417.582857114273, 6482.870342593216, 5788.768449261982, 5949.958757217556, 6252.9062064069985, 6264.749410023548, 6274.058891166362], "value": 6068.00302092673, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.1284960843853, 799.1928152565906, 1118.284298049248, 1143.9599614013507, 1146.1117633833783, 36536.63463365369, 37304.06739831502, 37306.253874922484, 37388.43917341242, 37402.438963415574, 39551.40672889909, 39836.0, 39972.8008159755, 41555.0, 41622.58377416225, 6091.451769340759, 8648.913510864873, 8777.473351598892, 8778.473291602524, 8789.428687135316, 6208.037754148092, 7658.655360508763, 8516.957415212915, 8523.57382130894, 8538.316934645245, 8562.914370856306, 8591.570421478933, 8617.224449799516, 8627.266682332009, 8636.827263454727, 8526.87209691855, 8567.400281980234, 8579.957100214458, 8583.313334933191, 8615.82768344636, 8430.620622072021, 8523.14768523149, 8593.882795236641, 8599.527026013568, 8608.354373421957, 38584.80707596444, 38626.613733862585, 38653.80673096645, 38661.0, 38794.03014924621, 3026.6065411496584, 3029.712177343149, 3036.802607830484, 3048.7560995120557, 3058.8776448942017, 27216.183514494598, 27229.86385068062, 27621.30946726328, 27714.307142321402, 27820.582691259646, 2748.3403983044127, 2788.7768978481877, 2805.223795074185, 2883.3656595548946, 2885.5960165576835, 307887.0, 310376.99999999773, 310997.0, 311086.0, 312465.0, 39406.60593394087, 40139.0, 49551.50448495469, 49612.50387496151, 49635.25547116796, 41347.96630084212, 49598.25602615964, 49635.25547116796, 49965.75017124891, 50026.0, 14256.35846386915, 14379.64050898715, 14619.049761765451, 14619.26903654813, 14643.560693179008, 11706.414679266003, 11737.354445505409, 11739.765204696028, 11753.588624398277, 11763.647090587296, 13922.443102275796, 13924.164550127025, 13964.092333998258, 13979.370928308048, 14105.294735263196, 5357.785688572413, 5476.959377718221, 5811.302643682785, 5829.941700583025, 5834.416558344216, 915.5330781301451, 960.2653969713175, 994.7761753605525, 1002.9498525073717, 1018.5976539266948, 23613.88193059058, 24539.0, 24586.016559337426, 24613.63079553808, 24822.0, 965.9855102173473, 1078.8327809189589, 1120.4117838134882, 1126.2285334545863, 1130.8756036835941, 10153.39079655223, 12529.561465348668, 12542.874571254353, 12570.87429125697, 12571.0, 441056.0, 452266.0, 474100.0, 476950.0, 478082.0, 55218.619534511156, 55638.44361556332, 55711.0, 55834.0, 55839.0, 1453.8546145385376, 1464.1507925403453, 1521.0873475914584, 1523.2764436892387, 1529.6175956010998, 23655.81720913982, 23944.019217284284, 24065.639015414432, 24074.971401167735, 24158.885726107907, 39477.618283359945, 39658.00854978533, 39687.80936571908, 39692.801535992134, 39800.402993954536, 425225.0, 426088.0, 426793.0, 427544.0, 427839.0, 25848.224553263433, 25931.740682592932, 26129.477410451305, 31189.68810311868, 33533.664663353054, 38898.4165237516, 39291.821245362684, 39437.6056239434, 40133.59866401416, 40216.0, 26817.195484135507, 27663.712124620717, 28069.719302806712, 28477.572836407056, 28695.713042869305, 27789.722102778713, 28351.0, 28593.14220573386, 28612.998545050825, 28779.712202877705, 34625.82687086549, 35181.29637407289, 35301.646983529834, 35334.46998294976, 35335.64664353323, 27783.861080694467, 28957.85521072381, 29361.11916642504, 29551.704482954894, 29963.700362996093, 20119.59760804805, 20529.2814751483, 22760.430989225402, 22927.88536057309, 22943.655845162], "value": 17454.90708144749, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.0919636084373, 428.85633312840196, 428.90349671323975, 431.5684315684315, 451.5439406199739, 21087.683684744723, 21730.456738581524, 21781.663492444353, 22595.887020564875, 22942.88528557359, 22439.32682019538, 22635.886820565916, 23261.767382326172, 23325.65011524824, 23598.410039749015, 5000.749962501878, 6034.788782392618, 6225.688715564226, 6237.781677641284, 6243.219597550306, 5958.433948774859, 6134.785282515113, 6143.815685529441, 6150.323464418932, 6158.414950579688, 5862.912056319158, 6083.117947897552, 6096.4513193812545, 6111.602745821529, 6173.567850250484, 6092.482139018189, 6121.479674227696, 6149.846253843667, 6151.507879369639, 6159.969200154015, 5345.305110335631, 5403.297571315743, 5452.427495113036, 5461.317335333083, 5472.671639701591, 28024.879004839782, 28081.0, 28124.578131328046, 28207.435851283066, 28582.428351433064, 1824.6806808808537, 1993.1130646862152, 2007.0, 2100.705901173837, 2106.988645450177, 16092.59768505785, 16638.001719896845, 16765.664686706197, 16843.494695159166, 17447.12764361777, 1619.8218195998431, 1670.6575152093912, 1677.7315629499221, 1693.4327000454862, 1697.295622316737, 141434.0, 147769.7834532483, 148683.0, 150288.0, 153415.0, 12341.297610716749, 12497.75004499905, 12505.06212034101, 12574.43415046325, 12599.433025513874, 16476.42332518356, 16491.257893394824, 16492.4227652034, 16529.173541322885, 16532.421365252158, 7684.654190561439, 8614.913850861536, 8902.287816974627, 8949.642014319354, 9108.681196158102, 6412.422881940648, 8135.959320203361, 8416.242538171471, 8559.486430814171, 8573.314134869195, 6664.866702666017, 6675.7997260082275, 7525.887111693329, 7534.736284230135, 7542.811429714191, 2071.2543484345624, 2073.139647046489, 2081.9791802082086, 2087.1129769847753, 2253.4704344479032, 815.355868863603, 912.1516989200107, 937.381328323317, 941.580996456584, 971.7813491964392, 20423.608924081098, 20997.95010249512, 21055.789442105688, 21151.048202830603, 21159.25942592002, 973.3673112476805, 978.7195833707444, 992.9453880036667, 997.9201663866874, 1003.8243307421318, 10998.12015038795, 11004.0, 11102.555897764161, 11114.999650031536, 11142.387168705636, 587540.0, 591871.0, 599170.0, 694123.0, 701438.0, 97889.51055244678, 134342.0, 165508.0, 168098.0, 170565.0, 1162.604714397109, 1195.9222650527688, 1224.2287358963974, 1240.7146356338092, 1245.3959829482512, 20299.188032478534, 21502.817345046173, 21705.131794728666, 21797.237096701538, 21806.454838628528, 57809.69479353815, 58445.0, 58458.707706461195, 59151.70424147852, 59931.400685994326, 79760.40479190499, 80758.59620701859, 82192.58903705444, 87745.0, 90452.1909561792, 8326.375521835766, 8518.14818518168, 8536.658533658585, 8637.784055398666, 9246.121618446237, 13631.727365452438, 14032.78950815759, 14359.0, 14481.565553033426, 14580.708385832011, 11092.667219983414, 11723.965620627556, 12017.339046352534, 12084.274943503418, 12108.697282568006, 9223.538823058687, 9232.353735238468, 9476.47879366614, 9481.52592370365, 9511.857322140311, 11268.774624507629, 11359.0, 11640.476178572155, 11708.297502149899, 11961.461734221806, 12560.371981401076, 12970.675733106747, 13001.284929328978, 13404.597862064154, 13657.385417656427, 11641.825372619249, 11793.587224447103, 11872.821907671565, 12060.638180854588, 12130.454129564367], "value": 10467.039533476302, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1193.6777070191042, 1252.9812052819207, 1423.9644008899777, 1439.1724758263995, 1455.5633310006995, 9088.318376121793, 9448.527573621317, 10728.088112510426, 11041.613543525978, 11046.447677616126, 10708.357498550073, 10810.243282970196, 10810.945945270283, 10830.350178989265, 10849.349039057663, 2066.772655007948, 2443.938901527463, 2479.00839664134, 2486.105002199207, 2489.2781093482868, 1855.4155441036098, 2035.307995281604, 2146.0879126371306, 2467.728549859514, 2490.6762120924254, 2432.8322405245494, 2459.766322199397, 2464.112919349033, 2466.5560199164142, 2469.1481438903547, 2403.4111642647576, 2410.6986626671664, 2420.782129608344, 2421.83047186697, 2423.6728041714496, 2383.1778036577352, 2397.436602398435, 2402.8077753779658, 2412.047241339661, 2424.139430502164, 10838.458077096113, 11102.888971110266, 11105.888941110567, 11113.222074454792, 11130.944345278303, 6233.2208473940755, 6458.93541064588, 6462.289148193742, 6482.351764823527, 6496.0, 3781.9054523636855, 3849.268638958589, 4006.939895901535, 4087.693422993287, 4092.1201941582403, 1071.244772435431, 1088.7223757941674, 1118.815395459748, 1165.97085072873, 1204.0187247393435, 15514.301856416489, 15701.136437495934, 15731.977421467567, 15748.92125539365, 15768.999999999885, 5540.2243685884005, 5734.139879018097, 5827.65034097947, 5865.35481097079, 5931.288245410492, 12977.675558111123, 13000.0, 13011.869881301254, 13021.934890325489, 13034.674133146558, 6333.96833015832, 6500.577462464925, 6508.3491650835485, 6524.771632992917, 6536.084948107269, 5784.305883294031, 5954.285485741739, 6008.759649614054, 6017.127516510169, 6025.216721826135, 4397.922508985255, 4447.3329000650165, 4469.486009108994, 4534.886627834265, 4543.000539881221, 1233.2846948769693, 1388.208721029024, 1390.7218556288783, 1404.1013751199241, 1428.2358937968138, 723.913130424342, 734.4161391693616, 752.8005078654161, 764.6826566974754, 790.952542847431, 5840.269966254218, 6419.454346380517, 6616.96691516549, 6635.469162467088, 6696.1629796275465, 490.7938665760389, 564.7797359029981, 570.7574280930588, 584.7076461769116, 586.7946218823378, 3656.542932133483, 3743.3449146399817, 3782.149016471327, 3784.508013958223, 3832.137769001952, 12699.492020319269, 13961.720765584632, 14173.149611023371, 14204.502842400467, 14225.85774142266, 13844.30778461073, 13996.510122145879, 14125.0, 14249.216293103771, 14272.571822845332, 9784.853227201598, 10086.596536138471, 10140.138088262433, 10274.537645805807, 10406.115480184118, 5337.8131765388025, 6203.503719702503, 6362.713677884413, 6425.164728585254, 6479.449246814073, 12098.879011209776, 12233.938830305791, 12379.195352302071, 12437.813432798695, 12483.56307529232, 12631.747365052832, 12810.487580496676, 12836.16564923277, 12902.354882255662, 13114.213147211198, 8832.072632373509, 9136.08639136081, 9159.313051521029, 9170.174684278447, 9179.219766319671, 9924.503774811086, 10048.54781534818, 10078.395296282246, 10115.544800483849, 10130.341527800669, 8411.957940210259, 8472.771448140109, 8523.659053638034, 8776.824463510566, 8797.0, 8258.42191046621, 8466.830663386574, 8541.87187192205, 8559.828803424021, 8582.485050896967, 7697.8845317319165, 7726.884096738441, 7745.883811742716, 7854.4894581851995, 7858.685652573833, 4971.9254211186135, 5717.942820571908, 5773.971130144322, 5972.611780234271, 6149.538784591263, 5888.116782482662, 5888.411158884077, 6001.0, 6040.697965101639, 6121.510279177744], "value": 5124.597296982208, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.5832684125366, 391.75906817307356, 399.1246918885809, 406.0660480893943, 406.1997864207512, 5149.330587023686, 5712.400197979205, 6130.877382452349, 6159.414855588723, 6185.278617279021, 5775.884482310351, 5916.290045194572, 5940.643561386309, 6104.236970378703, 6139.618585818189, 4214.925194075522, 4749.548792864673, 4774.904501909959, 4777.304057059737, 4787.760611969405, 3973.8410463581567, 4746.0, 4768.833090841821, 4789.222251094357, 4806.3511425957395, 3866.326989360119, 4560.315952607102, 4657.641366502113, 4669.509701481346, 4705.952940470603, 3957.9010524736823, 4683.929741053887, 4723.4568024677255, 4733.881652958669, 4740.928886066712, 3659.249853779969, 4171.20747058061, 4195.223883581517, 4204.5375008749015, 4209.684273679486, 4978.6763860348965, 4983.950160498385, 5909.586328956974, 5987.9101813472835, 6053.909191362133, 5462.0, 5474.671519708791, 5493.7802487900435, 5520.420355862657, 5522.91715624266, 6515.837104072436, 6518.869622607569, 6532.856750068719, 6545.836354091138, 6563.310852360529, 4087.591240875918, 4301.397804307399, 4319.740815551046, 4332.981749288914, 4346.978265108686, 5635.746391412396, 6634.23706273775, 6726.587416642462, 6732.865342693168, 6844.1444819397575, 3751.249750050001, 4341.435613370241, 4395.692301538926, 4398.846040388571, 4418.867433976986, 4212.53662097169, 4369.97305633176, 4463.62059224963, 4471.731696098244, 4481.663875209372, 4437.866863994085, 5166.070107380708, 5166.199239117943, 5171.32772739549, 5242.082635538765, 6425.357464253537, 6472.792684754947, 6477.805665830033, 6489.935100649028, 6502.804915852532, 1454.2365258239508, 1508.6379268975368, 1511.3727802961648, 1541.3603354607712, 1542.1364036139798, 864.6195673903462, 940.7271891151627, 974.8196583631909, 986.7237173591406, 997.8403455447238, 5162.535371816554, 5595.608307418523, 5897.026990546512, 5937.842120786405, 6077.574569780073, 5551.2228288039705, 5846.6784326862435, 6159.6612186329285, 6641.302663220292, 6645.401913827779, 5288.524032837063, 5794.246747922743, 5854.015971799335, 5934.258217722785, 5995.640261584319, 2778.8054836161273, 2886.480433521986, 3430.3139372125675, 3448.534372891511, 3449.896503104911, 6205.28639206488, 6398.680065996681, 6643.701033453506, 6768.9661551691925, 6807.455403567703, 6796.728130874809, 6806.93193068063, 6808.2170550386345, 6819.0, 6828.283030281749, 6383.904241436289, 6753.358430948953, 6781.796546103625, 6807.693653785592, 6852.588844669336, 5576.693281869536, 6242.969909964805, 6458.935410645833, 6620.768273110418, 6667.566608170648, 6354.4916406686425, 6513.739450422119, 6558.934410655832, 6560.507961902781, 6611.768588099393, 5575.637583557056, 5678.990135228336, 5771.94228057714, 6211.782587609412, 6779.762708305186, 3700.27844570317, 3923.450716899579, 4305.31115021595, 4374.387585738063, 4378.671599629977, 6154.353792851686, 6197.73782569368, 6446.194225721785, 6579.4736421087155, 6669.332267093007, 4925.064237794808, 5277.2004157006895, 5635.267415235993, 5748.338941021836, 6516.5462304976545, 6512.185976752906, 6528.151340325728, 6534.901976470262, 6563.868722625616, 6569.868602628017, 5118.566801295643, 6378.394052564999, 6410.4871610272, 6417.133686952202, 6425.0, 6528.836779080561, 6532.477401807749, 6546.574472659262, 6558.80323590293, 6571.967140164268, 6130.4060944155735, 6344.936550634619, 6392.872142557216, 6415.903761443488, 6441.838954026187], "value": 4691.202470231041, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [634.8888944434724, 676.6244734172532, 713.1442269276865, 734.7758933525273, 735.1509007096805, 14913.850861491383, 15231.086134831918, 15780.684386312267, 17387.478375648734, 18293.17680704368, 16711.582210444743, 16775.161241937883, 16847.157642117905, 17221.7416738749, 17492.825071749277, 962.7641227899157, 976.3604838830578, 977.5258999385293, 978.33473238198, 978.8042391521689, 944.7354740672623, 965.6909788867565, 974.0454354732367, 978.0, 980.7499087732654, 1021.7445638590352, 1022.3661329975425, 1023.2376879224988, 1023.3399457350005, 1030.634124885666, 1012.6658202793061, 1014.9951547967459, 1016.9644062457816, 1022.4785359466651, 1027.342500799489, 754.9924500754977, 760.638696619107, 760.8288135169598, 763.7288762489347, 772.7874834420523, 16135.435259765853, 16227.513174604663, 16576.83423165765, 16591.917040414843, 16874.578135546588, 14659.92670036643, 14684.779728304084, 14700.779488307684, 14809.407623695039, 14826.110433374031, 11534.884651153465, 11649.883501164964, 11723.7069073273, 11917.761644767055, 12212.694682633006, 3000.6249218847643, 3027.3928295972023, 3060.693930606944, 3136.5138403547485, 3167.984160079208, 100312.0, 104552.47723761409, 105257.47371263172, 105276.0, 107526.99999999921, 10381.84427233592, 10878.945605271923, 11263.774724505463, 11467.254628449124, 11677.474513646906, 24338.756612434005, 24828.875855620605, 24869.140048894245, 24977.0, 25025.499490010097, 7548.169701332849, 7590.8861367079535, 7604.619769011527, 7690.692372305157, 7833.686652533949, 4106.958930410658, 4166.437530933345, 4211.389348544444, 4225.746455212697, 4229.238737027365, 4145.17096580685, 4236.872893813191, 4251.617354438054, 4252.638525725348, 4256.9996050928, 841.4993079118037, 866.3978534918172, 866.9479831210148, 880.9691660791841, 888.7733627924838, 533.5651444073068, 546.5900574569073, 576.7865889620867, 580.4253788749108, 631.8767840271195, 8014.959925200454, 10002.349847259904, 10109.595616175418, 10366.481675916324, 10665.520051597698, 523.1890569617078, 546.7047794191171, 567.9772809087673, 577.1804038265673, 600.7446835095067, 4542.818287268472, 4765.428148622118, 4766.94810400977, 4815.662903596714, 4837.830675926326, 90484.09515904756, 95561.04438955522, 95621.0, 96741.51629241809, 98535.0, 73333.63333183406, 78894.2110578887, 81553.59223203965, 83635.327293455, 83886.0, 72600.63699681468, 73228.63385683038, 73685.0, 73812.0, 74712.62643686747, 9135.58889849945, 9262.814743704954, 9516.85724714116, 9561.235101191749, 9595.904040959502, 36286.818565907, 36782.0, 37057.88826335214, 37290.62709372872, 37825.0, 39190.608093918694, 48538.0, 49600.751996239786, 49874.750626246634, 50386.74806625943, 11431.771364572496, 11490.681683520608, 11548.480318385526, 11688.415579221175, 12133.39333033327, 11501.482433290355, 12420.565280215149, 12489.93755031219, 12645.367731613567, 12782.296973666165, 11593.304401735923, 11642.70893227676, 11844.644660660195, 12027.819582706092, 12196.207246528948, 11684.637530748485, 11721.0, 12190.695232619255, 12197.207181533173, 12342.938285308515, 11464.0, 11685.532578696757, 12171.634850954486, 12193.0, 12335.0, 7899.881501777593, 8689.261412779731, 8845.0, 8881.245094167069, 9155.450672959643, 8243.958780206061, 8670.566471676517, 9751.3661611995, 9762.072603102693, 9878.4566848824], "value": 7305.445722607894, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1519.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 112.0, 112.0, 111.0, 113.0, 156.0, 159.0, 112.0, 112.0], "value": 121.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 113.0, 110.0, 149.0, 110.0, 110.0, 110.0, 109.0, 133.0, 105.0], "value": 119.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 256.0, 200.0, 190.0, 257.0, 203.0, 182.0, 183.0, 185.0, 183.0], "value": 203.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 61.0, 89.0, 61.0, 58.0, 61.0, 60.0, 77.0, 63.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 96.0, 117.0, 98.0, 71.0, 70.0, 84.0, 69.0, 68.0, 85.0], "value": 85.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 96.0, 131.0, 85.0, 123.0, 97.0, 116.0, 131.0, 131.0, 88.0], "value": 108.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 97.0, 102.0, 95.0, 96.0], "value": 96.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 93.0, 137.0, 136.0, 86.0, 83.0, 84.0, 83.0, 109.0, 129.0], "value": 102.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 60.0, 60.0, 61.0, 61.0, 63.0, 61.0, 61.0, 61.0, 62.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 65.0, 56.0, 62.0, 63.0, 57.0, 59.0, 56.0, 57.0], "value": 58.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 96.0, 101.0, 80.0, 83.0, 89.0, 100.0, 99.0, 102.0, 84.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 144.0, 131.0, 137.0, 137.0, 139.0, 136.0, 137.0, 187.0, 159.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 185.0, 186.0, 188.0, 188.0, 219.0, 199.0, 188.0, 184.0, 184.0], "value": 191.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 66.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0], "value": 67.8, "unit": "score"}]}]} 04:38:17 INFO - Return code: 0 04:38:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:38:18 ERROR - # TBPL SUCCESS # 04:38:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:38:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:38:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:38:18 INFO - 2017-03-27 04:38:18.074 screenresolution[1651:9840] starting screenresolution argv=screenresolution get 04:38:18 INFO - 2017-03-27 04:38:18.077 screenresolution[1651:9840] Display 0: 1600x1200x32@60 04:38:18 INFO - 2017-03-27 04:38:18.083 screenresolution[1652:9843] starting screenresolution argv=screenresolution list 04:38:18 INFO - Available Modes on Display 0 04:38:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:38:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:38:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:38:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:38:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:38:18 INFO - Intel Iris: 04:38:18 INFO - Chipset Model: Intel Iris 04:38:18 INFO - Type: GPU 04:38:18 INFO - Bus: Built-In 04:38:18 INFO - VRAM (Dynamic, Max): 1536 MB 04:38:18 INFO - Vendor: Intel (0x8086) 04:38:18 INFO - Device ID: 0x0a2e 04:38:18 INFO - Revision ID: 0x0009 04:38:18 INFO - Displays: 04:38:18 INFO - Display: 04:38:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:38:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:38:18 INFO - Display Serial Number: 1600x1200 60 04:38:18 INFO - Main Display: Yes 04:38:18 INFO - Mirror: Off 04:38:18 INFO - Online: Yes 04:38:18 INFO - Rotation: Supported 04:38:18 INFO - Return code: 0 04:38:18 INFO - Running post-action listener: _resource_record_post_action 04:38:18 INFO - [mozharness: 2017-03-27 11:38:18.335203Z] Finished run-tests step (success) 04:38:18 INFO - Running post-run listener: _resource_record_post_run 04:38:18 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 36672512; Write bytes: 514170880; Read time: 239; Write time: 1566 04:38:18 INFO - TinderboxPrint: CPU usage
53.6% 04:38:18 INFO - TinderboxPrint: I/O read bytes / time
36,672,512 / 239 04:38:18 INFO - TinderboxPrint: I/O write bytes / time
514,170,880 / 1,566 04:38:18 INFO - TinderboxPrint: CPU idle
2,236.7 (46.4%) 04:38:18 INFO - TinderboxPrint: CPU system
960.1 (19.9%) 04:38:18 INFO - TinderboxPrint: CPU user
1,620.9 (33.6%) 04:38:18 INFO - TinderboxPrint: Swap in / out
248,176,640 / 0 04:38:18 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200378880; Write bytes: 195188736; Read time: 15772; Write time: 404 04:38:18 INFO - run-tests - Wall time: 1184s; CPU: 54.0%; Read bytes: 35714048; Write bytes: 299153408; Read time: 226; Write time: 1109 04:38:18 INFO - Running post-run listener: _upload_blobber_files 04:38:18 INFO - Blob upload gear active. 04:38:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:38:18 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 04:38:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:38:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:38:20 INFO - (blobuploader) - INFO - Open directory for files ... 04:38:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:38:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:38:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:38:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:38:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:38:24 INFO - (blobuploader) - INFO - Done attempting. 04:38:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:38:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:38:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:38:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:38:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:38:27 INFO - (blobuploader) - INFO - Done attempting. 04:38:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:38:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:38:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:38:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:38:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:38:31 INFO - (blobuploader) - INFO - Done attempting. 04:38:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:38:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:38:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:38:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:38:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:38:40 INFO - (blobuploader) - INFO - Done attempting. 04:38:40 INFO - (blobuploader) - INFO - Iteration through files over. 04:38:40 INFO - Return code: 0 04:38:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:38:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:38:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c4d9d5b2f2df577f5588f8c04cb48cb86620eab454f837c1173b56c9fca9af5ebbd3c84a256d63f9c01efcad87a2db4acd7ac63b754b941826072019ed3f94f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfafe46d8c61fb293b79123d82b68898b8f892fd3d2ec94f079a8baf74ae610f5f3ad571d524ac4979ecac12eb0e48965dbc1f477dee68947d8b304c8b562343", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b19a1171fe2b060ab0db619bfd6b9a2ff7528a843ae42a7bdf24e48f01d1d8ba14e45fa829c32d85e2945a5ad34b32f6de73d79207ad2fcd5371cce4e9ea2b34", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ccae3faf9d04ea59069bd8b6796e3f2f171c3c6e0c157ed0800e0a8b3571abd66ded63f769ab99edd1a05a3739aac99efcea258bd8b646d03f6cf8cc574edf7d"} 04:38:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:38:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:38:40 INFO - Contents: 04:38:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c4d9d5b2f2df577f5588f8c04cb48cb86620eab454f837c1173b56c9fca9af5ebbd3c84a256d63f9c01efcad87a2db4acd7ac63b754b941826072019ed3f94f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfafe46d8c61fb293b79123d82b68898b8f892fd3d2ec94f079a8baf74ae610f5f3ad571d524ac4979ecac12eb0e48965dbc1f477dee68947d8b304c8b562343", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b19a1171fe2b060ab0db619bfd6b9a2ff7528a843ae42a7bdf24e48f01d1d8ba14e45fa829c32d85e2945a5ad34b32f6de73d79207ad2fcd5371cce4e9ea2b34", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ccae3faf9d04ea59069bd8b6796e3f2f171c3c6e0c157ed0800e0a8b3571abd66ded63f769ab99edd1a05a3739aac99efcea258bd8b646d03f6cf8cc574edf7d"} 04:38:40 INFO - Running post-run listener: copy_logs_to_upload_dir 04:38:40 INFO - Copying logs to upload dir... 04:38:40 INFO - mkdir: /builds/slave/test/build/upload/logs 04:38:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1303.370030 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 43 secs) (at 2017-03-27 04:38:40.863104) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 04:38:40.866700) ========= 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.SUNZAxVopV/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.XXzOtccd7f/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c4d9d5b2f2df577f5588f8c04cb48cb86620eab454f837c1173b56c9fca9af5ebbd3c84a256d63f9c01efcad87a2db4acd7ac63b754b941826072019ed3f94f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfafe46d8c61fb293b79123d82b68898b8f892fd3d2ec94f079a8baf74ae610f5f3ad571d524ac4979ecac12eb0e48965dbc1f477dee68947d8b304c8b562343", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b19a1171fe2b060ab0db619bfd6b9a2ff7528a843ae42a7bdf24e48f01d1d8ba14e45fa829c32d85e2945a5ad34b32f6de73d79207ad2fcd5371cce4e9ea2b34", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ccae3faf9d04ea59069bd8b6796e3f2f171c3c6e0c157ed0800e0a8b3571abd66ded63f769ab99edd1a05a3739aac99efcea258bd8b646d03f6cf8cc574edf7d"} build_url:https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010960 build_url: 'https://queue.taskcluster.net/v1/task/Qfo7j77YQzeNWv8maZjXtA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c4d9d5b2f2df577f5588f8c04cb48cb86620eab454f837c1173b56c9fca9af5ebbd3c84a256d63f9c01efcad87a2db4acd7ac63b754b941826072019ed3f94f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfafe46d8c61fb293b79123d82b68898b8f892fd3d2ec94f079a8baf74ae610f5f3ad571d524ac4979ecac12eb0e48965dbc1f477dee68947d8b304c8b562343", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b19a1171fe2b060ab0db619bfd6b9a2ff7528a843ae42a7bdf24e48f01d1d8ba14e45fa829c32d85e2945a5ad34b32f6de73d79207ad2fcd5371cce4e9ea2b34", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ccae3faf9d04ea59069bd8b6796e3f2f171c3c6e0c157ed0800e0a8b3571abd66ded63f769ab99edd1a05a3739aac99efcea258bd8b646d03f6cf8cc574edf7d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 04:38:40.901681) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 04:38:40.902022) ========= 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.SUNZAxVopV/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.XXzOtccd7f/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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.005228 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 04:38:40.951191) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 04:38:40.951520) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 04:38:40.951857) ========= ========= Total master_lag: 0.18 =========