builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0103 starttime: 1492186183.51 results: success (0) buildid: 20170414081107 builduid: eee54abb4316429ea3a8dad4907baa4a revision: 567d5c33be379ef8d206e3b64afec4a6a92920d6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-14 09:09:43.511678) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-14 09:09:43.512163) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-14 09:09:43.512453) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-14 09:09:43.537337) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 09:09:43.537648) ========= 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.fAUVBGl2EB/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.auz1Y19nAg/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-14 09:09:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.75M=0.001s 2017-04-14 09:09:43 (7.75 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.250571 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 09:09:43.811578) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 09:09:43.811890) ========= 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.fAUVBGl2EB/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.auz1Y19nAg/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.020191 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 09:09:43.860476) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-14 09:09:43.860910) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 567d5c33be379ef8d206e3b64afec4a6a92920d6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 567d5c33be379ef8d206e3b64afec4a6a92920d6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fAUVBGl2EB/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.auz1Y19nAg/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-14 09:09:43,944 truncating revision to first 12 chars 2017-04-14 09:09:43,944 Setting DEBUG logging. 2017-04-14 09:09:43,944 attempt 1/10 2017-04-14 09:09:43,944 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/567d5c33be37?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-14 09:09:44,838 unpacking tar archive at: mozilla-aurora-567d5c33be37/testing/mozharness/ program finished with exit code 0 elapsedTime=1.151168 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-14 09:09:45.036224) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-14 09:09:45.036588) ========= script_repo_revision: 567d5c33be379ef8d206e3b64afec4a6a92920d6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-14 09:09:45.036984) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-14 09:09:45.037247) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-14 09:09:45.057115) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-14 09:09:45.057507) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fAUVBGl2EB/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.auz1Y19nAg/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 09:09:45 INFO - MultiFileLogger online at 20170414 09:09:45 in /builds/slave/test 09:09:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:09:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:09:45 INFO - {'append_to_log': False, 09:09:45 INFO - 'base_work_dir': '/builds/slave/test', 09:09:45 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:09:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:09:45 INFO - 'branch': 'Mozilla-Aurora', 09:09:45 INFO - 'buildbot_json_path': 'buildprops.json', 09:09:45 INFO - 'config_files': ('talos/mac_config.py',), 09:09:45 INFO - 'default_actions': ('clobber', 09:09:45 INFO - 'read-buildbot-config', 09:09:45 INFO - 'download-and-extract', 09:09:45 INFO - 'populate-webroot', 09:09:45 INFO - 'create-virtualenv', 09:09:45 INFO - 'install', 09:09:45 INFO - 'run-tests'), 09:09:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:09:45 INFO - 'download_minidump_stackwalk': True, 09:09:45 INFO - 'download_symbols': 'ondemand', 09:09:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:09:45 INFO - 'tooltool.py': '/tools/tooltool.py', 09:09:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:09:45 INFO - '/tools/misc-python/virtualenv.py')}, 09:09:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:09:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:09:45 INFO - 'gecko_profile': False, 09:09:45 INFO - 'gecko_profile_interval': 0, 09:09:45 INFO - 'installer_path': 'installer.exe', 09:09:45 INFO - 'log_level': 'info', 09:09:45 INFO - 'log_name': 'talos', 09:09:45 INFO - 'log_to_console': True, 09:09:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:09:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:09:45 INFO - 'opt_config_files': (), 09:09:45 INFO - 'pip_index': False, 09:09:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:09:45 INFO - 'cmd': ('bash', 09:09:45 INFO - '-c', 09:09:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:09:45 INFO - 'enabled': True, 09:09:45 INFO - 'halt_on_failure': False, 09:09:45 INFO - 'name': 'check_screen_resolution'},), 09:09:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:09:45 INFO - 'cmd': ('bash', 09:09:45 INFO - '-c', 09:09:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:09:45 INFO - 'enabled': True, 09:09:45 INFO - 'halt_on_failure': False, 09:09:45 INFO - 'name': 'check_screen_resolution'},), 09:09:45 INFO - 'run_cmd_checks_enabled': True, 09:09:45 INFO - 'suite': 'dromaeojs', 09:09:45 INFO - 'system_bits': '32', 09:09:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:09:45 INFO - 'title': 't-yosemite-r7-0103', 09:09:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:09:45 INFO - 'use_talos_json': True, 09:09:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:09:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:09:45 INFO - 'work_dir': 'build'} 09:09:45 INFO - [mozharness: 2017-04-14 16:09:45.269169Z] Running clobber step. 09:09:45 INFO - Running pre-action listener: _resource_record_pre_action 09:09:45 INFO - Running main action method: clobber 09:09:45 INFO - rmtree: /builds/slave/test/build 09:09:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:09:46 INFO - Running post-action listener: _resource_record_post_action 09:09:46 INFO - [mozharness: 2017-04-14 16:09:46.820329Z] Finished clobber step (success) 09:09:46 INFO - [mozharness: 2017-04-14 16:09:46.820472Z] Running read-buildbot-config step. 09:09:46 INFO - Running pre-action listener: _resource_record_pre_action 09:09:46 INFO - Running main action method: read_buildbot_config 09:09:46 INFO - Using buildbot properties: 09:09:46 INFO - { 09:09:46 INFO - "project": "", 09:09:46 INFO - "product": "firefox", 09:09:46 INFO - "script_repo_revision": "production", 09:09:46 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:09:46 INFO - "repository": "", 09:09:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:09:46 INFO - "buildid": "20170414081107", 09:09:46 INFO - "pgo_build": "False", 09:09:46 INFO - "basedir": "/builds/slave/test", 09:09:46 INFO - "buildnumber": 0, 09:09:46 INFO - "slavename": "t-yosemite-r7-0103", 09:09:46 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:09:46 INFO - "platform": "yosemite_r7", 09:09:46 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:09:46 INFO - "branch": "mozilla-aurora", 09:09:46 INFO - "repo_path": "releases/mozilla-aurora", 09:09:46 INFO - "revision": "567d5c33be379ef8d206e3b64afec4a6a92920d6", 09:09:46 INFO - "stage_platform": "macosx64", 09:09:46 INFO - "builduid": "eee54abb4316429ea3a8dad4907baa4a", 09:09:46 INFO - "slavebuilddir": "test" 09:09:46 INFO - } 09:09:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 09:09:46 INFO - Running post-action listener: _resource_record_post_action 09:09:46 INFO - [mozharness: 2017-04-14 16:09:46.823420Z] Finished read-buildbot-config step (success) 09:09:46 INFO - [mozharness: 2017-04-14 16:09:46.823524Z] Running download-and-extract step. 09:09:46 INFO - Running pre-action listener: _resource_record_pre_action 09:09:46 INFO - Running main action method: download_and_extract 09:09:46 INFO - mkdir: /builds/slave/test/build/tests 09:09:46 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')]} 09:09:46 INFO - trying https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 09:09:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:09:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 09:09:47 INFO - Downloaded 1199 bytes. 09:09:47 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:09:47 INFO - Using the following test package requirements: 09:09:47 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:09:47 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:09:47 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 09:09:47 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:09:47 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 09:09:47 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:09:47 INFO - u'jsshell-mac.zip'], 09:09:47 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:09:47 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 09:09:47 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:09:47 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:09:47 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 09:09:47 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:09:47 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 09:09:47 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:09:47 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 09:09:47 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:09:47 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 09:09:47 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:09:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:09:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:09:47 INFO - Fetch https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:09:49 INFO - Expected file size: 51791846 09:09:49 INFO - Obtained file size: 51791846 09:10:05 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:10:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:10:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:10:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:10:07 INFO - Expected file size: 51791846 09:10:07 INFO - Obtained file size: 51791846 09:10:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 09:10:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:10:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 09:10:23 INFO - Expected file size: 14404424 09:10:23 INFO - Obtained file size: 14404424 09:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:23 INFO - trying https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:10:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 09:10:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 09:10:25 INFO - Downloaded 74745502 bytes. 09:10:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:10:25 INFO - mkdir: /builds/slave/test/properties 09:10:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:10:25 INFO - Writing to file /builds/slave/test/properties/build_url 09:10:25 INFO - Contents: 09:10:25 INFO - build_url:https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:10:27 INFO - Running post-action listener: _resource_record_post_action 09:10:27 INFO - Running post-action listener: set_extra_try_arguments 09:10:27 INFO - [mozharness: 2017-04-14 16:10:27.045050Z] Finished download-and-extract step (success) 09:10:27 INFO - [mozharness: 2017-04-14 16:10:27.045186Z] Running populate-webroot step. 09:10:27 INFO - Running pre-action listener: _resource_record_pre_action 09:10:27 INFO - Running main action method: populate_webroot 09:10:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:10:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:10:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:10:27 INFO - '--tppagecycles', 09:10:27 INFO - '7'], 09:10:27 INFO - 'tests': ['tsvgm']}}, 09:10:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:10:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:10:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:10:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:10:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:10:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:10:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:10:27 INFO - 'talos_options': ['--disable-e10s'], 09:10:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:10:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:10:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:10:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:10:27 INFO - 'talos_options': ['--disable-e10s'], 09:10:27 INFO - 'tests': ['damp', 'tps']}, 09:10:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:10:27 INFO - 'tests': ['damp', 'tps']}, 09:10:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:10:27 INFO - 'tests': ['dromaeo_dom']}, 09:10:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:10:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:10:27 INFO - 'tests': ['basic_compositor_video']}, 09:10:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:10:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:10:27 INFO - 'tests': ['a11yr', 09:10:27 INFO - 'ts_paint', 09:10:27 INFO - 'tpaint', 09:10:27 INFO - 'sessionrestore', 09:10:27 INFO - 'sessionrestore_no_auto_restore', 09:10:27 INFO - 'tabpaint']}, 09:10:27 INFO - 'other-e10s': {'tests': ['a11yr', 09:10:27 INFO - 'ts_paint', 09:10:27 INFO - 'tpaint', 09:10:27 INFO - 'sessionrestore', 09:10:27 INFO - 'sessionrestore_no_auto_restore', 09:10:27 INFO - 'tabpaint']}, 09:10:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:10:27 INFO - 'ts_paint', 09:10:27 INFO - 'tpaint', 09:10:27 INFO - 'sessionrestore', 09:10:27 INFO - 'sessionrestore_no_auto_restore', 09:10:27 INFO - 'tabpaint']}, 09:10:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:10:27 INFO - 'ts_paint', 09:10:27 INFO - 'tpaint', 09:10:27 INFO - 'sessionrestore', 09:10:27 INFO - 'sessionrestore_no_auto_restore', 09:10:27 INFO - 'tabpaint']}, 09:10:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:10:27 INFO - 'tests': ['a11yr', 09:10:27 INFO - 'ts_paint', 09:10:27 INFO - 'tpaint', 09:10:27 INFO - 'sessionrestore', 09:10:27 INFO - 'sessionrestore_no_auto_restore', 09:10:27 INFO - 'tabpaint']}, 09:10:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:10:27 INFO - 'tests': ['a11yr', 09:10:27 INFO - 'ts_paint', 09:10:27 INFO - 'tpaint', 09:10:27 INFO - 'sessionrestore', 09:10:27 INFO - 'sessionrestore_no_auto_restore', 09:10:27 INFO - 'tabpaint']}, 09:10:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:10:27 INFO - 'tests': ['tsvgx', 09:10:27 INFO - 'tsvgr_opacity', 09:10:27 INFO - 'tart', 09:10:27 INFO - 'tscrollx', 09:10:27 INFO - 'cart', 09:10:27 INFO - 'tsvg_static']}, 09:10:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:10:27 INFO - 'tsvgr_opacity', 09:10:27 INFO - 'tart', 09:10:27 INFO - 'tscrollx', 09:10:27 INFO - 'cart', 09:10:27 INFO - 'tsvg_static']}, 09:10:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:10:27 INFO - 'talos_options': ['--disable-e10s'], 09:10:27 INFO - 'tests': ['tp5o']}, 09:10:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:10:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:10:27 INFO - 'talos_options': ['--disable-e10s', 09:10:27 INFO - '--xperf_path', 09:10:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:10:27 INFO - 'tests': ['tp5n']}, 09:10:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:10:27 INFO - 'talos_options': ['--xperf_path', 09:10:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:10:27 INFO - 'tests': ['tp5n']}}, 09:10:27 INFO - 'talos.zip': {'path': '', 09:10:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:10:27 INFO - Running post-action listener: _resource_record_post_action 09:10:27 INFO - [mozharness: 2017-04-14 16:10:27.063734Z] Finished populate-webroot step (success) 09:10:27 INFO - [mozharness: 2017-04-14 16:10:27.063843Z] Running create-virtualenv step. 09:10:27 INFO - Running pre-action listener: _resource_record_pre_action 09:10:27 INFO - Running main action method: create_virtualenv 09:10:27 INFO - Creating virtualenv /builds/slave/test/build/venv 09:10:27 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 09:10:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:10:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:10:27 INFO - Using real prefix '/tools/python27' 09:10:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:10:27 INFO - Installing distribute.............................................................................................................................................................................................done. 09:10:30 INFO - Installing pip.................done. 09:10:30 INFO - Return code: 0 09:10:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:10:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:10:31 INFO - Reading from file tmpfile_stdout 09:10:31 INFO - Output received: 09:10:31 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) 09:10:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:10:31 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')]} 09:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:31 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')]} 09:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:31 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 0x10cd95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb10596a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10ca9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb68be8>, '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.fAUVBGl2EB/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.auz1Y19nAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 09:10:31 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 09:10:31 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 09:10:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fAUVBGl2EB/Render', 09:10:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:31 INFO - 'HOME': '/Users/cltbld', 09:10:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:31 INFO - 'LOGNAME': 'cltbld', 09:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:31 INFO - 'MOZ_NO_REMOTE': '1', 09:10:31 INFO - 'NO_EM_RESTART': '1', 09:10:31 INFO - 'PAGER': '/bin/cat', 09:10:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:31 INFO - 'PWD': '/builds/slave/test', 09:10:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:31 INFO - 'SHELL': '/bin/bash', 09:10:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.auz1Y19nAg/Listeners', 09:10:31 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:10:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:31 INFO - 'USER': 'cltbld', 09:10:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:31 INFO - 'XPC_FLAGS': '0x0', 09:10:31 INFO - 'XPC_SERVICE_NAME': '0', 09:10:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:31 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 09:10:31 INFO - Cleaning up... 09:10:31 INFO - Return code: 0 09:10:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:10:31 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')]} 09:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:31 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')]} 09:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:31 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 0x10cd95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb10596a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10ca9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb68be8>, '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.fAUVBGl2EB/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.auz1Y19nAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 09:10:31 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 09:10:31 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 09:10:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fAUVBGl2EB/Render', 09:10:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:31 INFO - 'HOME': '/Users/cltbld', 09:10:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:31 INFO - 'LOGNAME': 'cltbld', 09:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:31 INFO - 'MOZ_NO_REMOTE': '1', 09:10:31 INFO - 'NO_EM_RESTART': '1', 09:10:31 INFO - 'PAGER': '/bin/cat', 09:10:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:31 INFO - 'PWD': '/builds/slave/test', 09:10:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:31 INFO - 'SHELL': '/bin/bash', 09:10:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.auz1Y19nAg/Listeners', 09:10:31 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:10:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:31 INFO - 'USER': 'cltbld', 09:10:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:31 INFO - 'XPC_FLAGS': '0x0', 09:10:31 INFO - 'XPC_SERVICE_NAME': '0', 09:10:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:31 INFO - Downloading/unpacking psutil>=3.1.1 09:10:31 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 09:10:31 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 09:10:31 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 09:10:31 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 09:10:31 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 09:10:31 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 09:10:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:10:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:10:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:10:35 INFO - Installing collected packages: psutil 09:10:35 INFO - Running setup.py install for psutil 09:10:35 INFO - building 'psutil._psutil_osx' extension 09:10:35 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 09:10:35 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 09:10:35 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 09:10:36 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 09:10:36 INFO - building 'psutil._psutil_posix' extension 09:10:36 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 09:10:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:10:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:10:36 INFO - ^ 09:10:36 INFO - 1 warning generated. 09:10:36 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 09:10:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:10:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:10:36 INFO - Successfully installed psutil 09:10:36 INFO - Cleaning up... 09:10:36 INFO - Return code: 0 09:10:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:10:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:36 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 0x10cd95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb10596a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10ca9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb68be8>, '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.fAUVBGl2EB/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.auz1Y19nAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 09:10:36 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 09:10:36 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 09:10:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fAUVBGl2EB/Render', 09:10:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:36 INFO - 'HOME': '/Users/cltbld', 09:10:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:36 INFO - 'LOGNAME': 'cltbld', 09:10:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:36 INFO - 'MOZ_NO_REMOTE': '1', 09:10:36 INFO - 'NO_EM_RESTART': '1', 09:10:36 INFO - 'PAGER': '/bin/cat', 09:10:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:36 INFO - 'PWD': '/builds/slave/test', 09:10:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:36 INFO - 'SHELL': '/bin/bash', 09:10:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.auz1Y19nAg/Listeners', 09:10:36 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:10:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:36 INFO - 'USER': 'cltbld', 09:10:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:36 INFO - 'XPC_FLAGS': '0x0', 09:10:36 INFO - 'XPC_SERVICE_NAME': '0', 09:10:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:10:36 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 09:10:36 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 09:10:36 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 09:10:36 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 09:10:36 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 09:10:36 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 09:10:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:10:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:10:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:10:39 INFO - Installing collected packages: mozsystemmonitor 09:10:39 INFO - Running setup.py install for mozsystemmonitor 09:10:39 INFO - Successfully installed mozsystemmonitor 09:10:39 INFO - Cleaning up... 09:10:39 INFO - Return code: 0 09:10:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:10:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:40 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')]} 09:10:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:40 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 0x10cd95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb10596a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10ca9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb68be8>, '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.fAUVBGl2EB/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.auz1Y19nAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 09:10:40 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 09:10:40 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 09:10:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fAUVBGl2EB/Render', 09:10:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:40 INFO - 'HOME': '/Users/cltbld', 09:10:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:40 INFO - 'LOGNAME': 'cltbld', 09:10:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:40 INFO - 'MOZ_NO_REMOTE': '1', 09:10:40 INFO - 'NO_EM_RESTART': '1', 09:10:40 INFO - 'PAGER': '/bin/cat', 09:10:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:40 INFO - 'PWD': '/builds/slave/test', 09:10:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:40 INFO - 'SHELL': '/bin/bash', 09:10:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.auz1Y19nAg/Listeners', 09:10:40 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:10:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:40 INFO - 'USER': 'cltbld', 09:10:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:40 INFO - 'XPC_FLAGS': '0x0', 09:10:40 INFO - 'XPC_SERVICE_NAME': '0', 09:10:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:40 INFO - Downloading/unpacking jsonschema==2.5.1 09:10:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:10:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:44 INFO - Downloading functools32-3.2.3-2.tar.gz 09:10:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:10:44 INFO - warning: no files found matching '*.txt' 09:10:44 INFO - no previously-included directories found matching 'build' 09:10:44 INFO - no previously-included directories found matching 'dist' 09:10:44 INFO - no previously-included directories found matching '.git*' 09:10:44 INFO - Installing collected packages: jsonschema, functools32 09:10:44 INFO - Running setup.py install for functools32 09:10:44 INFO - warning: no files found matching '*.txt' 09:10:44 INFO - no previously-included directories found matching 'build' 09:10:44 INFO - no previously-included directories found matching 'dist' 09:10:44 INFO - no previously-included directories found matching '.git*' 09:10:44 INFO - Successfully installed jsonschema functools32 09:10:44 INFO - Cleaning up... 09:10:44 INFO - Return code: 0 09:10:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:10: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')]} 09:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10: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')]} 09:10:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cd95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb10596a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10ca9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb68be8>, '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.fAUVBGl2EB/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.auz1Y19nAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 09:10:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:10:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:10:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fAUVBGl2EB/Render', 09:10:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:44 INFO - 'HOME': '/Users/cltbld', 09:10:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:44 INFO - 'LOGNAME': 'cltbld', 09:10:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:44 INFO - 'MOZ_NO_REMOTE': '1', 09:10:44 INFO - 'NO_EM_RESTART': '1', 09:10:44 INFO - 'PAGER': '/bin/cat', 09:10:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:44 INFO - 'PWD': '/builds/slave/test', 09:10:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:44 INFO - 'SHELL': '/bin/bash', 09:10:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.auz1Y19nAg/Listeners', 09:10:44 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:10:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:44 INFO - 'USER': 'cltbld', 09:10:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:44 INFO - 'XPC_FLAGS': '0x0', 09:10:44 INFO - 'XPC_SERVICE_NAME': '0', 09:10:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:10:45 INFO - Cleaning up... 09:10:45 INFO - Return code: 0 09:10:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cd95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb10596a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10ca9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb68be8>, '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.fAUVBGl2EB/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.auz1Y19nAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 09:10:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:10:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:10:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fAUVBGl2EB/Render', 09:10:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:45 INFO - 'HOME': '/Users/cltbld', 09:10:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:45 INFO - 'LOGNAME': 'cltbld', 09:10:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:45 INFO - 'MOZ_NO_REMOTE': '1', 09:10:45 INFO - 'NO_EM_RESTART': '1', 09:10:45 INFO - 'PAGER': '/bin/cat', 09:10:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:45 INFO - 'PWD': '/builds/slave/test', 09:10:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:45 INFO - 'SHELL': '/bin/bash', 09:10:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.auz1Y19nAg/Listeners', 09:10:45 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:10:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:45 INFO - 'USER': 'cltbld', 09:10:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:45 INFO - 'XPC_FLAGS': '0x0', 09:10:45 INFO - 'XPC_SERVICE_NAME': '0', 09:10:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:45 INFO - Downloading/unpacking blobuploader==1.2.4 09:10: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 09:10: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 09:10: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 09:10: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 09:10: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 09:10: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 09:10:47 INFO - Downloading blobuploader-1.2.4.tar.gz 09:10:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:10:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:10:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:49 INFO - Downloading docopt-0.6.1.tar.gz 09:10:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:10:49 INFO - Installing collected packages: blobuploader, requests, docopt 09:10:49 INFO - Running setup.py install for blobuploader 09:10:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:10:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:10:49 INFO - Running setup.py install for requests 09:10:49 INFO - Running setup.py install for docopt 09:10:50 INFO - Successfully installed blobuploader requests docopt 09:10:50 INFO - Cleaning up... 09:10:50 INFO - Return code: 0 09:10:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cd95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb10596a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10ca9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb68be8>, '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.fAUVBGl2EB/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.auz1Y19nAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 09:10:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:10:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:10:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fAUVBGl2EB/Render', 09:10:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:50 INFO - 'HOME': '/Users/cltbld', 09:10:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:50 INFO - 'LOGNAME': 'cltbld', 09:10:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:50 INFO - 'MOZ_NO_REMOTE': '1', 09:10:50 INFO - 'NO_EM_RESTART': '1', 09:10:50 INFO - 'PAGER': '/bin/cat', 09:10:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:50 INFO - 'PWD': '/builds/slave/test', 09:10:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:50 INFO - 'SHELL': '/bin/bash', 09:10:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.auz1Y19nAg/Listeners', 09:10:50 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:10:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:50 INFO - 'USER': 'cltbld', 09:10:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:50 INFO - 'XPC_FLAGS': '0x0', 09:10:50 INFO - 'XPC_SERVICE_NAME': '0', 09:10:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:10:50 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-yi7YIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:10:50 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-SpMLcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:10:50 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-U0HcuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:10:50 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-1QjR8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:10:50 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-7VN7Em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:10:51 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-aFFdso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:10:51 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-Ina_7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:10:51 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-8X9p1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:10:51 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-F8_liU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:10:51 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-TQCAMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:10:51 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-XBhdq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:10:51 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-38rYA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:10:51 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-bZAw7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:10:51 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-twUK4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:51 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-NKYRB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:10:52 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-BBJEjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:10:52 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-pdGGJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:10:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:10:52 INFO - Running setup.py install for manifestparser 09:10:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:10:52 INFO - Running setup.py install for mozcrash 09:10:52 INFO - Running setup.py install for mozdebug 09:10:52 INFO - Running setup.py install for mozdevice 09:10:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:10:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:10:52 INFO - Running setup.py install for mozfile 09:10:52 INFO - Running setup.py install for mozhttpd 09:10:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:10:53 INFO - Running setup.py install for mozinfo 09:10:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:10:53 INFO - Running setup.py install for mozInstall 09:10:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:10:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:10:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:10:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:10:53 INFO - Running setup.py install for mozleak 09:10:53 INFO - Running setup.py install for mozlog 09:10:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:10:53 INFO - Running setup.py install for moznetwork 09:10:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:10:53 INFO - Running setup.py install for mozprocess 09:10:54 INFO - Running setup.py install for mozprofile 09:10:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:10:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:10:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:10:54 INFO - Running setup.py install for mozrunner 09:10:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:10:54 INFO - Running setup.py install for mozscreenshot 09:10:54 INFO - Running setup.py install for moztest 09:10:54 INFO - Running setup.py install for mozversion 09:10:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:10:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:10:54 INFO - Cleaning up... 09:10:54 INFO - Return code: 0 09:10:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10:54 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')]} 09:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:54 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')]} 09:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:54 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 0x10cd95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb10596a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10ca9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb68be8>, '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.fAUVBGl2EB/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.auz1Y19nAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 09:10:54 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 09:10:54 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 09:10:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fAUVBGl2EB/Render', 09:10:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:54 INFO - 'HOME': '/Users/cltbld', 09:10:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:54 INFO - 'LOGNAME': 'cltbld', 09:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:54 INFO - 'MOZ_NO_REMOTE': '1', 09:10:54 INFO - 'NO_EM_RESTART': '1', 09:10:54 INFO - 'PAGER': '/bin/cat', 09:10:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:54 INFO - 'PWD': '/builds/slave/test', 09:10:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:54 INFO - 'SHELL': '/bin/bash', 09:10:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.auz1Y19nAg/Listeners', 09:10:54 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:10:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:54 INFO - 'USER': 'cltbld', 09:10:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:54 INFO - 'XPC_FLAGS': '0x0', 09:10:54 INFO - 'XPC_SERVICE_NAME': '0', 09:10:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:10:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-_ZM8yX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:10:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-sqXy90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:10:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-iszWOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:10:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-ZvKano-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:10:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-t0trm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:10:55 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)) 09:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:10:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-HcKACL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:10:55 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)) 09:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:10:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-jkhmyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:10:55 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)) 09:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:10:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-2fFvft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:10:55 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)) 09:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:10:55 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-dt7P3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:10:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:10:56 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-5fJEmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:10:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:10:56 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-2OR4Q1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:10:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:10:56 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-T_kaY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:10:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:10:56 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-AlQR_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:10:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:10:56 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-iKYuMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:10:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:56 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-0jKp7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:56 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)) 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:10:56 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-a39gQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:10:56 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)) 09:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:10:56 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-m98pLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:10:56 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)) 09:10:56 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)) 09:10:56 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)) 09:10:56 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)) 09:10:56 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)) 09:10:56 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)) 09:10:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:10: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 09:10: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 09:10: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 09:10: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 09:10: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 09:10: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 09:10:59 INFO - Downloading blessings-1.6.tar.gz 09:10:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:10:59 INFO - Installing collected packages: blessings 09:10:59 INFO - Running setup.py install for blessings 09:10:59 INFO - Successfully installed blessings 09:10:59 INFO - Cleaning up... 09:10:59 INFO - Return code: 0 09:10:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:10:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:10:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:00 INFO - Reading from file tmpfile_stdout 09:11:00 INFO - Current package versions: 09:11:00 INFO - blessings == 1.6 09:11:00 INFO - blobuploader == 1.2.4 09:11:00 INFO - docopt == 0.6.1 09:11:00 INFO - functools32 == 3.2.3-2 09:11:00 INFO - jsonschema == 2.5.1 09:11:00 INFO - manifestparser == 1.1 09:11:00 INFO - mozInstall == 1.12 09:11:00 INFO - mozcrash == 1.0 09:11:00 INFO - mozdebug == 0.1 09:11:00 INFO - mozdevice == 0.49 09:11:00 INFO - mozfile == 1.2 09:11:00 INFO - mozhttpd == 0.7 09:11:00 INFO - mozinfo == 0.9 09:11:00 INFO - mozleak == 0.1 09:11:00 INFO - mozlog == 3.4 09:11:00 INFO - moznetwork == 0.27 09:11:00 INFO - mozprocess == 0.25 09:11:00 INFO - mozprofile == 0.28 09:11:00 INFO - mozrunner == 6.13 09:11:00 INFO - mozscreenshot == 0.1 09:11:00 INFO - mozsystemmonitor == 0.3 09:11:00 INFO - moztest == 0.8 09:11:00 INFO - mozversion == 1.4 09:11:00 INFO - psutil == 3.1.1 09:11:00 INFO - requests == 1.2.3 09:11:00 INFO - wsgiref == 0.1.2 09:11:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11: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')]} 09:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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')]} 09:11:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:00 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 0x10cd95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb10596a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10ca9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb68be8>, '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.fAUVBGl2EB/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.auz1Y19nAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 09:11:00 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 09:11:00 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 09:11:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fAUVBGl2EB/Render', 09:11:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:00 INFO - 'HOME': '/Users/cltbld', 09:11:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:00 INFO - 'LOGNAME': 'cltbld', 09:11:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:00 INFO - 'MOZ_NO_REMOTE': '1', 09:11:00 INFO - 'NO_EM_RESTART': '1', 09:11:00 INFO - 'PAGER': '/bin/cat', 09:11:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:00 INFO - 'PWD': '/builds/slave/test', 09:11:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:00 INFO - 'SHELL': '/bin/bash', 09:11:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.auz1Y19nAg/Listeners', 09:11:00 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:11:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:00 INFO - 'USER': 'cltbld', 09:11:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:00 INFO - 'XPC_FLAGS': '0x0', 09:11:00 INFO - 'XPC_SERVICE_NAME': '0', 09:11:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:00 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)) 09:11:00 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)) 09:11:00 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)) 09:11:00 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)) 09:11:00 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)) 09:11:00 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)) 09:11:00 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)) 09:11:00 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)) 09:11:00 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)) 09:11:00 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)) 09:11:00 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)) 09:11:00 INFO - Cleaning up... 09:11:00 INFO - Return code: 0 09:11:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:11: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')]} 09:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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')]} 09:11:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:00 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 0x10cd95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb10596a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10ca9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb68be8>, '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.fAUVBGl2EB/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.auz1Y19nAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 09:11:00 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 09:11:00 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 09:11:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fAUVBGl2EB/Render', 09:11:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:00 INFO - 'HOME': '/Users/cltbld', 09:11:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:00 INFO - 'LOGNAME': 'cltbld', 09:11:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:00 INFO - 'MOZ_NO_REMOTE': '1', 09:11:00 INFO - 'NO_EM_RESTART': '1', 09:11:00 INFO - 'PAGER': '/bin/cat', 09:11:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:00 INFO - 'PWD': '/builds/slave/test', 09:11:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:00 INFO - 'SHELL': '/bin/bash', 09:11:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.auz1Y19nAg/Listeners', 09:11:00 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:11:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:00 INFO - 'USER': 'cltbld', 09:11:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:00 INFO - 'XPC_FLAGS': '0x0', 09:11:00 INFO - 'XPC_SERVICE_NAME': '0', 09:11:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:11:00 INFO - Cleaning up... 09:11:00 INFO - Return code: 0 09:11:00 INFO - Running post-action listener: _resource_record_post_action 09:11:00 INFO - Running post-action listener: _start_resource_monitoring 09:11:00 INFO - Starting resource monitoring. 09:11:00 INFO - [mozharness: 2017-04-14 16:11:00.719287Z] Finished create-virtualenv step (success) 09:11:00 INFO - [mozharness: 2017-04-14 16:11:00.720240Z] Running install step. 09:11:00 INFO - Running pre-action listener: _resource_record_pre_action 09:11:00 INFO - Running main action method: install 09:11:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:00 INFO - Reading from file tmpfile_stdout 09:11:00 INFO - Detecting whether we're running mozinstall >=1.0... 09:11:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:11:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:11:01 INFO - Reading from file tmpfile_stdout 09:11:01 INFO - Output received: 09:11:01 INFO - Usage: mozinstall [options] installer 09:11:01 INFO - Options: 09:11:01 INFO - -h, --help show this help message and exit 09:11:01 INFO - -d DEST, --destination=DEST 09:11:01 INFO - Directory to install application into. [default: 09:11:01 INFO - "/builds/slave/test"] 09:11:01 INFO - --app=APP Application being installed. [default: firefox] 09:11:01 INFO - mkdir: /builds/slave/test/build/application 09:11:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:11:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:11:21 INFO - Reading from file tmpfile_stdout 09:11:21 INFO - Output received: 09:11:21 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:11:21 INFO - Running post-action listener: _resource_record_post_action 09:11:21 INFO - [mozharness: 2017-04-14 16:11:21.044099Z] Finished install step (success) 09:11:21 INFO - [mozharness: 2017-04-14 16:11:21.044228Z] Running run-tests step. 09:11:21 INFO - Running pre-action listener: _resource_record_pre_action 09:11:21 INFO - Running main action method: run_tests 09:11:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:11:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:11:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:11:21 INFO - 2017-04-14 09:11:21.058 screenresolution[1905:15766] starting screenresolution argv=screenresolution get 09:11:21 INFO - 2017-04-14 09:11:21.061 screenresolution[1905:15766] Display 0: 1600x1200x32@60 09:11:21 INFO - 2017-04-14 09:11:21.067 screenresolution[1906:15769] starting screenresolution argv=screenresolution list 09:11:21 INFO - Available Modes on Display 0 09:11:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:11:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:11:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:11:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:11:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:11:21 INFO - Intel Iris: 09:11:21 INFO - Chipset Model: Intel Iris 09:11:21 INFO - Type: GPU 09:11:21 INFO - Bus: Built-In 09:11:21 INFO - VRAM (Dynamic, Max): 1536 MB 09:11:21 INFO - Vendor: Intel (0x8086) 09:11:21 INFO - Device ID: 0x0a2e 09:11:21 INFO - Revision ID: 0x0009 09:11:21 INFO - Displays: 09:11:21 INFO - Display: 09:11:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:11:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:11:21 INFO - Display Serial Number: 1600x1200 60 09:11:21 INFO - Main Display: Yes 09:11:21 INFO - Mirror: Off 09:11:21 INFO - Online: Yes 09:11:21 INFO - Rotation: Supported 09:11:21 INFO - Return code: 0 09:11:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:11:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:11:21 INFO - Python 2.7.3 09:11:21 INFO - Return code: 0 09:11:21 INFO - grabbing minidump binary from tooltool 09:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:21 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 0x10cb73d30>, '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 0x10ca9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb68be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:11:21 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 09:11:21 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 09:11:21 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 09:11:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:11:21 INFO - Return code: 0 09:11:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:11:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:11:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:11:21 INFO - ENV: RUST_BACKTRACE is now 1 09:11:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:11:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:11:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0103', '--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 09:11:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0103 --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 09:11:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fAUVBGl2EB/Render', 09:11:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:11:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:11:21 INFO - 'HOME': '/Users/cltbld', 09:11:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:11:21 INFO - 'LOGNAME': 'cltbld', 09:11:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:11:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:21 INFO - 'MOZ_NO_REMOTE': '1', 09:11:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:11:21 INFO - 'NO_EM_RESTART': '1', 09:11:21 INFO - 'PAGER': '/bin/cat', 09:11:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:21 INFO - 'PWD': '/builds/slave/test', 09:11:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:11:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:11:21 INFO - 'RUST_BACKTRACE': '1', 09:11:21 INFO - 'SHELL': '/bin/bash', 09:11:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.auz1Y19nAg/Listeners', 09:11:21 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 09:11:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:11:21 INFO - 'USER': 'cltbld', 09:11:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:11:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:21 INFO - 'XPC_FLAGS': '0x0', 09:11:21 INFO - 'XPC_SERVICE_NAME': '0', 09:11:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:11:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0103', '--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 09:11:21 INFO - mozversion application_buildid: 20170414081107 09:11:21 INFO - mozversion application_changeset: 567d5c33be379ef8d206e3b64afec4a6a92920d6 09:11:21 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:11:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:11:21 INFO - mozversion application_name: Firefox 09:11:21 INFO - mozversion application_remotingname: firefox-dev 09:11:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:11:21 INFO - mozversion application_vendor: Mozilla 09:11:21 INFO - mozversion application_version: 54.0a2 09:11:21 INFO - mozversion platform_buildid: 20170414081107 09:11:21 INFO - mozversion platform_changeset: 567d5c33be379ef8d206e3b64afec4a6a92920d6 09:11:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:11:21 INFO - mozversion platform_version: 54.0a2 09:11:21 INFO - using testdate: 1492186281 09:11:21 INFO - actual date: 1492186281 09:11:21 INFO - starting webserver on 'localhost:52336' 09:11:21 INFO - SUITE-START | Running 2 tests 09:11:21 INFO - TEST-START | dromaeo_css 09:11:21 INFO - operating with platform_type : mac_ 09:11:21 INFO - Initialising browser for dromaeo_css test... 09:11:21 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpIQU84X/profile http://localhost:52336/getInfo.html) 09:11:24 INFO - PROCESS | 1916 | __metrics Screen width/height:1600/1200 09:11:24 INFO - PROCESS | 1916 | colorDepth:24 09:11:24 INFO - PROCESS | 1916 | Browser inner width/height: 1024/689 09:11:24 INFO - PROCESS | 1916 | __metrics 09:11:24 INFO - PROCESS | 1916 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:11:24 INFO - PROCESS | 1916 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:11:24 INFO - TEST-INFO | 1916: exit 0 09:11:24 INFO - Browser initialized. 09:11:24 INFO - Running cycle 1/1 for dromaeo_css test... 09:11:25 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpIQU84X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:11:37 INFO - PROCESS | 1918 | RSS: Main: 210894848 09:11:37 INFO - PROCESS | 1918 | 09:14:40 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52336/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52336/tests/dromaeo/cssquery-ext.html) 09:14:43 INFO - PROCESS | 1918 | RSS: Main: 397975552 09:14:43 INFO - PROCESS | 1918 | 09:16:26 INFO - PROCESS | 1918 | ************************* 09:16:26 INFO - PROCESS | 1918 | A coding exception was thrown and uncaught in a Task. 09:16:26 INFO - PROCESS | 1918 | 09:16:26 ERROR - PROCESS | 1918 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:16:26 INFO - PROCESS | 1918 | Full stack: 09:16:26 INFO - PROCESS | 1918 | ************************* 09:16:26 INFO - PROCESS | 1918 | ************************* 09:16:26 INFO - PROCESS | 1918 | A coding exception was thrown and uncaught in a Task. 09:16:26 INFO - PROCESS | 1918 | 09:16:26 ERROR - PROCESS | 1918 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:16:26 INFO - PROCESS | 1918 | Full stack: 09:16:26 INFO - PROCESS | 1918 | ************************* 09:16:26 INFO - PROCESS | 1918 | ************************* 09:16:26 INFO - PROCESS | 1918 | A coding exception was thrown and uncaught in a Task. 09:16:26 INFO - PROCESS | 1918 | 09:16:26 ERROR - PROCESS | 1918 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:16:26 INFO - PROCESS | 1918 | Full stack: 09:16:26 INFO - PROCESS | 1918 | ************************* 09:16:26 INFO - PROCESS | 1918 | ************************* 09:16:26 INFO - PROCESS | 1918 | A coding exception was thrown and uncaught in a Task. 09:16:26 INFO - PROCESS | 1918 | 09:16:26 ERROR - PROCESS | 1918 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:16:26 INFO - PROCESS | 1918 | Full stack: 09:16:26 INFO - PROCESS | 1918 | ************************* 09:17:45 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52336/tests/dromaeo/cssquery-ext.html (next: http://localhost:52336/tests/dromaeo/cssquery-jquery.html) 09:17:46 INFO - PROCESS | 1918 | RSS: Main: 349487104 09:17:46 INFO - PROCESS | 1918 | 09:20:48 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52336/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52336/tests/dromaeo/cssquery-mootools.html) 09:20:49 INFO - PROCESS | 1918 | RSS: Main: 325476352 09:20:49 INFO - PROCESS | 1918 | 09:23:52 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52336/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52336/tests/dromaeo/cssquery-prototype.html) 09:23:52 INFO - PROCESS | 1918 | RSS: Main: 320962560 09:23:52 INFO - PROCESS | 1918 | 09:26:56 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52336/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52336/tests/dromaeo/cssquery-yui.html) 09:26:57 INFO - PROCESS | 1918 | RSS: Main: 323002368 09:26:57 INFO - PROCESS | 1918 | 09:30:00 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:52336/tests/dromaeo/cssquery-yui.html (next: http://localhost:52336/tests/dromaeo/cssquery-dojo.html) 09:30:00 INFO - PROCESS | 1918 | RSS: Main: 433876992 09:30:00 INFO - PROCESS | 1918 | 09:30:00 INFO - PROCESS | 1918 | __start_tp_report 09:30:00 INFO - PROCESS | 1918 | _x_x_mozilla_page_load 09:30:00 INFO - PROCESS | 1918 | _x_x_mozilla_page_load_details 09:30:00 INFO - PROCESS | 1918 | |i|pagename|runs| 09:30:00 INFO - PROCESS | 1918 | |0;dojo.html;2779.583062540619;3298.4433976357154;3330.500424936259;3331.4336562784324;3335.933281334375;4678.064387122577;5576.609637325389;5667.716614169296;5685.289338832651;5696.3733989261145;4817.108834865552;5499.3675727291375;5599.776008959637;5651.32184137903;5660.377358490573;5142.125838607436;5151.356080489939;5244.082285600024;5252.343457067866;5253.4221235664045;4228.936565951513;5095.6942583444925;5104.923426148611;5132.8973420531565;5265.157574788015;4351.71713838601;5151.7681704323395;5165.431802501722;5171.922421163686;5221.817236396727;5209.765560549784;5220.895582088337;5223.895522089575;5238.109521381355;5251.159814429673;4957.603391728653;5128.435872054071;5148.6910785353;5157.252198431244;5158.69047857126;5529.861753456155;5536.750846211889;5541.168824676291;5667.121596152561;5695.658260504383;6199.132121502993;6227.875442491125;6260.09228661846;6266.843328916768;6269.937300626981;6216.502679785606;6287.088372186053;6292.590981586182;6303.968480157616;6304.621722696653;5531.723413829292;5653.123765816305;5662.009148399096;5807.983602869481;5823.941760582425;5502.477264659851;5929.762809487572;6286.937130628635;6365.809025729235;6393.168888044617;5182.481751824787;6095.116208149793;6115.816525504242;6165.290991536031;6208.4722798562625;6237.345078766755;6242.906356404657;6269.185005949289;6292.937070629327;6296.8425789354715;5974.9402505974385;5979.701014949235;5982.162497250389;6201.7519299228425;6230.7819226327765;6144.218176728788;6182.165407669997;6195.752169913243;6201.976673848854;6225.159603453566;4989.650724449254;5029.7736601853;5031.295618613397;5031.77357018935;5061.2661164131;4375.387445757597;4472.664550158686;4475.351074094303;4479.619232365286;4491.079328737601;5658.151277308354;5678.488935995781;5742.54059675225;5789.507891829157;5802.448767367177;6347.714352854133;6350.4602108820345;6354.52341074412;6358.904616430757;6364.64994425311;5763.7982670606325;5801.906340654763;5806.21616081832;5815.040518314515;5846.678432686158;5269.894602107936;5352.170413585901;5396.9730151348995;5400;5482.5888058394985;6312.747490100344;6372.012338087604;6383.5212359074185;6395.328490508429;6405.423511883952;6302.621842689453;6315.400036996478;6320.5891617044745;6330.335314791881;6334.334894835976;6213.2233470816145;6313.96843015782;6317.147185130132;6359.7456101755415;6397;6124.625058545555;6154.261488621215;6210.192674952227;6226.128342032029;6248.343923887926;5920.970395147997;6136.846578835565;6276.6547839869245;6298.842528936813;6313.873722525432;5795.043817770021;5874.324452687995;5887.882242355215;5926.525877929842;5969.70151492415;5073.594112471067;5906.881862362643;5912.67480288571;5933.910991335047;5935.080062590306;5796.275465566804;5811.79658711943;5824.708764561839;5830.912536312044;5837.066069429041;5242.501962313574;5557.055300598827;5914.763409463573;5969.492593129633;6028.8794224116145;5673.687947162945;5866.384029676822;5897.351291358033;5904.3210030845285;5917.733701983335 09:30:00 INFO - PROCESS | 1918 | |1;ext.html;695.5652717051843;743.182499250824;760.562676461035;818.4679958027282;829.5396055189375;27650.308742281428;27983.580246296322;28503.857480712595;28528.001519946807;28585.714142858564;27884.581731273996;31735.52396714051;40395;42028;42273.36589951153;7898.802529936769;9113.043130471304;9130.908690913073;9144.634214631407;9155.771105722377;7144.85710285794;7848.215178482134;8925.241354484877;8947.463152210888;9031.412958157698;7301.926980730178;10656.733581660443;10857;10865.239433239676;10866.130709543257;10721.571137154504;10762.461876906202;10772.892271077306;10779.245452818306;10787.029167374974;10600.734981625445;10666.306690065121;10687.091597214247;10697.786044279148;10698.30461020039;37475.25049499022;37606;37638.24723505515;37647;37683;3783.6405541473514;3797.1456422305037;3841.5774264830848;3860.0156959975025;3894.844206231747;25863.74136258651;26549;27266.45467090647;27572.724272757416;28536.14391568256;3044.9543256851166;3106.6582675905634;3179.2528755742383;3247.6102867655795;3281.803091814475;339996;341905.2904735485;342809;343125.9999999975;343235;51973;52121.7393913028;52149.7392513035;52257.7387113062;52322.47677523252;42620.78689606594;54421;54460.183097253575;54548.72725636346;54672.45327546674;14594.978351515505;14858.702825943421;14892.553223403314;14895.255237238094;14920.179390133648;12834;12855.871441285655;12858.164219325714;12863.935680321725;12878.613641590768;10867.021968022758;11091.611793587346;12215.26708397499;12809.679758006125;12872.034597405232;4736.230962326923;4895.335585900811;5486.147203454181;5666.801661941895;5691.203231547586;971.1939090554822;1096.9396683182342;1122.337820685783;1140.4582823158933;1169.859616845967;26324.473510529682;26459.73540264573;27050.458990820465;27094.916203351644;27100.86449567779;1222.9877701222874;1251.2680082152042;1262.671705356614;1270.7712611729792;1274.343712987812;13661.658458538417;13704;13925.79111313311;13965.301734913417;13972.510962116481;465863;466129;466165;466789;467745;51537.7423112882;55412;55429.78280868801;55439.72280138574;55444.72277638586;1418.049906562585;1440.3806363263689;1444.8441247002565;1445.096814490922;1449.159487497249;25159.622605660563;25865;25896.093636722624;25994.830232639197;26055;46253;46349;46423.53576464192;46532.83667908194;46546.53453465422;390892;393127;393161;393593;394281;29233.85383073071;30059.69940300569;31011;31062.844685776425;34623;37425.25149497049;38041.66854160202;38324.233515330096;38868.41697374598;39064.023399415244;21949;26856.731432686203;27450.588241176;27695.58456623112;28121.172123812707;25387.98448062057;27039.729602703723;27261.454770904867;28012.719872801827;28116;31223.687763122078;31484;32096.35807283888;32318.35363292674;32436.83781581077;25193.748062519142;25995.74004260009;28320.71679283263;28438.715612843607;28834.855825720737;21280.36158915235;22267.66598501056;22823;22867.656985144902;22906 09:30:00 INFO - PROCESS | 1918 | |2;jquery.html;377.1589355736707;381.9274337875805;404.13111809609336;410.60581841432224;419.6391103650861;12940.547080852171;14115.647108822284;17594;20725;21231.469213269676;21618.459538511546;21723.782762172374;22172.445688857788;22351;22685.20601778938;6346.524010699193;6467.450266727322;6494.5778524396;6585.473162147016;6673.93326066738;6469.191351081115;6494.707738151795;6597.4392176664805;6598.769043083493;6608.900866487006;5562.638428502155;6439.645819479927;6605.70274337656;6617.040529123275;6618.470522358224;6319.873602527924;6496.155499784998;6584.8024559263295;6599.868002639944;6600.273969863311;5489.204065410533;6424.93575064248;6429.38920802523;6429.517786166056;6436;23528;24431.63352549713;25854;26151.346216344555;26387.73612263872;2863.727945845162;2890.262982939358;2894.3921776426973;2923.093840909325;2940.1767505098605;15329.463468778651;15559.066456012675;15807.209639518092;15851.60370990723;15876.36494540217;1724.6981778188767;2000.614658616617;2012.818846303825;2017.939461816148;2054.0551346380685;171540;173925;176266.47467050716;177530;179361.20638793707;19352.710039293353;19369.709454358195;19637.70096662779;19679;19879.503012424517;31097;31367;31500.842495787376;31562.842185789385;31605.84197079;9621.807563848824;9871.506424678737;10009.349392289478;10199.439030853375;10232.437215953194;7166.677499512431;8108.39187060961;8324.833503329899;8401.789955251046;8605.063860631433;8948.955255223813;9087.137551671203;9141.451512909247;9208.12522810332;9241.21449676785;3285.655006224312;3725.2549490102087;3874.3994680824517;3874.5350557932666;3894.844206231719;975.6633961283346;979.4759803505091;983.8032393521324;1011.3780025284398;1038.7039693687263;19959.800401996086;20075.799242007393;20137.899310503355;20194.89902550478;20262;985.2955137077024;993.3841018568389;997;1005.4671024357102;1011.3881101933224;9661.565229564687;10018.996200759877;10226.437545934896;10461.26771126029;10517.316374435637;609375;687912;742198;764790;787516;154574;154728.67906981404;155570;160646.19676901776;160762;1148.5233628044434;1206.1376116077047;1254.2599866079056;1254.3602762591145;1259.716563773162;19346.4196074118;19387.51531211731;20090.598188035867;20117.19531218735;20178.59642807165;65659.0302290932;66352;66528.6673566629;66871.66564167148;67469;92792.53603731938;93298.53695534846;93439.53280233555;94714.57928131221;95759;10993.395363254778;11347.829782553103;11401.315921044765;11490.82763758561;11635.592754253561;12417.19288246261;12579;12928.612141635766;12950.80573791375;13018.15382000167;9203.769905752142;10757.73105672333;10758.13934885223;10955.56177752881;10995.61515346991;9329.673461428818;10101.89898101039;10111.696649100539;10173.23700722434;10265.794684106128;9163.633454662006;11148.275362101438;11396.373199474108;11419.371934543347;11490.82763758561;9406.858897116412;10209.489525523546;11327.433628318386;11454.255473394203;11633.53465861356;10997.505112269808;11019.228653994143;11259.605913793306;11261.21171517986;11284.250941104143 09:30:00 INFO - PROCESS | 1918 | |3;mootools.html;1481.1557412275004;1494.5889880282914;1533.4786172701279;1556.571942715753;1558.7272227360208;10679.466026698663;11271.492782824773;11300.265482743616;11303.547858085676;11319.434028298583;10494.37033777974;10826.079783218416;11068.169887258447;11083.334999900011;11089.667309980694;2325.16294134112;2356.9410764730846;2364.0189321431585;2364.113457453455;2364.6216605343147;2278.908843646252;2350.894209760565;2369.8222633302485;2375.228050883461;2378.2508509819436;2312.063614236237;2370.383700237942;2381.3570336009266;2390.247072172269;2390.8445951013214;2145.9892700536475;2209.591225623265;2324.2097686786406;2324.2213858357472;2356.823238257129;2349.4361353275262;2352.082687751761;2354.093673935547;2357.823163262762;2357.8821058946983;10882.911708829133;10959;10993.670189894316;10993.945030274877;11020;5362.892742145174;5443.482869127427;5608;5628.9305032043785;5629.437056294379;2790.9581356279673;3492.89521314361;3557.8221088945706;3575.5173051637958;3585.587657419404;1101.531848964195;1108.9778204435866;1168.2698313554029;1189.9167058305923;1196.5572738086876;15515.069095854173;15542.766858497132;15673.92163039189;15685.999999999885;15705.607359816097;3715.3126671565847;3779.848806047727;4431.933520997188;4529.320601909674;4606.032733126014;11541.826872596917;11550.018248448809;11574.47914843834;11584.884151158549;11594.47824847884;5621.409751976065;5622.7750889963945;5633.239512665819;5637.23897273871;5677.063284558001;4990.326305948723;5038.924416133761;5111.642185047085;5200.5059519345605;5260.28986086881;3741.2704522618374;3798.5945200276074;3804.6766024887643;3856.9614303857165;3874.3413619684156;893.2362829780574;926.8656044873455;961.485605201214;969.7333233360741;970.3595626886363;925.2551695884878;931.2270815223484;939.7556635274877;952.7808604021114;991.2714155095998;6067.332593414721;6260.280067792171;6537;6562.901556476657;6634.900476492857;957.7366224288377;960.841461158901;984.5667906121283;1001.7545701303227;1017.3285631483186;4173.6661067114555;4241.512226094039;4242.278812601865;4338.848140315074;4357.869263922087;12818.871811281955;13518.391672374766;13521.256330901895;13540;13545.796813047813;10934.125269978382;11266.94366528162;13454;13479.123856949464;13537.39081741324;9647.324687271965;10045.949770251102;10072.59709611622;10088.74778130538;10097.99020097999;6778.423833974168;6888.104546408935;7031.437485001085;7039.753608623673;7071;11915.76168476643;12189.573364932185;12285.57000504978;12289.1397602167;12339;12120.151589388659;12165.479315085615;12314.13801033955;12414.255144691348;12448.190867593577;8219.876701849596;8281.130481299377;8358;8393.034800997963;8401.873971890303;9990.050945160197;10081.143102836048;10185.592576296865;10196.388216706724;10220.335678180894;8641.697540586048;8713.695020674246;8716.302695784449;8720.171583699539;8772.605232764668;6980.930190698028;8461.450005749606;8543.871841922251;8552.529610871461;8584.570771461276;7732.690692372242;7773.727919522789;7868.527888326718;7869.921300786918;7876.330511906552;5913.438223368773;5939;6051.7579296827635;6063.333033366238;6094.725737341919;5949.375315591974;6009.549283803644;6062.333143354315;6134.7852825152695;6144.907826382519 09:30:00 INFO - PROCESS | 1918 | |4;prototype.html;377.44704008627366;382.0544153220777;382.5294887288635;389.9044734040159;392.29582898696844;3851.6918646508284;3895.1430685249234;4519.322101684745;4734.100520901026;4988.725620090898;3833.884983450494;4572.519885412034;4880.4875488073685;4881.267809828528;4925.113479573675;4123.546409894909;4165.812538435763;4193.727407718497;4206.516250631186;4263.3178691409385;3979.1245925896255;4145.253854306224;4182.79086045698;4227.112306415656;4335.501417336999;3434.3818112739696;4065.4714887064642;4169.812358443877;4228.915421691564;4232.6613870890405;3937.645611894929;4133.958660413417;4165.396017577465;4177.182925427449;4303.935440968388;3845.731018698981;3846.499955005859;3874.380099184117;3876.186000939806;3908.964124507008;4159.254891771417;4306.44016277885;4682.184020023704;4944.505549445063;4953.0683033616915;3848.076461649212;3958.940615890764;4256.531781504032;4394.143142087294;4828.879278018043;4703.600193983515;5172.508611681884;5299.894002119936;5323.391538146319;5375.946240537583;3802.923941521154;4119.949412899721;4177.370825378105;4310.676699247538;4321.395004699345;4305.203537345601;4662.3705799716945;5036.697798132087;5445.700486473203;5458.781648734085;3955.4462375267485;4085.7548547086676;4351.4995775485595;4371.977780442001;4374.300111982067;3677.1941041022415;4048.149888523384;4066.5933406659096;4078.3286947049137;4541.9545804542195;3975.5229372475487;4460.799264033127;4804.543568361;4851.684640498427;4940.431850337185;4782.641301902371;4813.0026039194045;5103.923441148311;5270.025045366619;5299.682019078868;1380.6814492159963;1464.2678660669665;1465.7654775236124;1482.2657490735864;1508.2052357694308;1086.4893500054961;1139.8974092331564;1142.828575713633;1146.5643055638805;1160.7736491384098;4442.97811503356;4670.813167473331;5007.39911210657;5092.9373603690565;5157.742112894341;5066.493350664904;5568.575326174455;5611.2144299798065;5615.630621627073;5678;4738.881527961759;4844.15227335215;4965.528274813887;4997.775100120503;5055.317532133115;2597.584386498189;2632.9736702633113;2647.8543680097778;2881.671669222052;2897.3191300044473;4944.640223938446;5120.948790512047;5427.887283107033;5777.3933736957015;5866.6799970006405;4426.712263702913;4846.975765121152;5081.644284900095;5555.972220138954;5714.485696287354;5591.35055158721;5606.299212598426;5611.719414029282;5662.377138514842;5669.659820410789;4870.512948705101;5410.702411367412;5441.863953401196;5487.835364939058;5521.365043019943;4526.705764125321;5170.37955445349;5180.7566184114785;5247.89504209906;5496.251874062968;5315.601329900351;5760.769569217185;5765.682887441231;5771.480566749013;5824.650520968756;3787.6212378763007;3798.993266784249;4095.89760255996;4412.602865742098;4419.359192916945;4756.476787553435;5286.809938483582;5497.422770609029;5523.7790488380015;5582.246396736551;4757.928631070606;5317.494837990384;5343.3587969443915;5349.572034237174;5360;4742.905141897212;5190.8961820764125;5352.759125839269;5388.595855310789;5468;4657.976710116428;4737.478877323423;4895.730734809477;5189.822193134563;5218.26944227816;4570.9943812361225;5126.948730512647;5336.599755018312;5356.473405079533;5377.983256278895;4077.653399461079;5209.661372010807;5213.40045894727;5214.530692237717;5227.503387178212 09:30:00 INFO - PROCESS | 1918 | |5;yui.html;702.673256935525;706.9646517674122;741.0588552538273;765.9961700191499;859.4284800607596;14882;14959.177245251507;15267.236638168102;15350.846491535081;15616.297266623014;12837.22976621401;12997.935010324934;14383;14522.782158267608;14540.854591454083;934.2339281788936;940.5673390240463;944.2398868910524;946.011418068117;946.597695979206;926.7173512078813;926.7822061815468;958.094600602431;964.2045312617084;974.2206235011962;998.331118150837;1003.2976916158682;1005.4369553050278;1007.2848277722788;1009.3590569988073;991.4992928571097;996.9651062212861;999.6451259802737;1001.159026417809;1001.329109496639;743.2901578992049;743.5167141358106;743.7619961612286;743.8363560016787;747.3460721868365;15316.693666126726;15638.609034774108;15708.607284817857;15801.288941997638;16072.357105715757;13866.93066534671;14033.228172450512;14039.438422463089;14177.220252886087;14192.290385480788;11584.884151158465;11705.12211584126;11978.460969256404;12129.272243665408;12443.377831108408;3552.5674560156162;3617.113807117247;3621.058524783549;3627.836747346376;3676.283124790667;55578.33373815886;82527;83564;107465.46267268692;111309.44345278303;10390.43539974082;10626.203034772423;10842.295250808673;10910.945445272882;11138;21534.892325538272;22199.1120355184;22204.888975555343;22213.888930555244;22248.332550023526;7009.9649501752165;7829.138794732575;7911.525308481395;7932.563708996061;7955.164707705723;4224.577542245751;4225.429567008476;4303.2469317869245;4356.564343565618;4389.846355377546;3408.50576666382;3506.1760486285702;3510.9648903511147;3512.016635342108;3524.665156810099;621.3351713666334;637.1398611873933;646.1890327638783;661.2593894837812;661.7617657643244;659.5712786688691;685.6400389795298;687.1856849633101;711.8967749676268;723.5767076260447;9597.712068637813;9991.03049631245;10130.645427410005;10131.392116473035;10196.694099177037;609.9878002439926;637.6843462486099;672.9636360005635;686.7905288886938;689.334791925784;4694.225452800318;4903.926441103386;4904.092742842586;4916.508349165055;4918.680285781413;104879;105670.47164764127;107221.4638926816;108036.45981770198;111193;73530.26469735234;79926.20073799304;88399.55800221086;88599.11400886037;89144;62309;69470.3052969474;69539.60920781556;75540.62229688927;77085.22914770893;9036.081575736394;11003;11265.14125169338;11359.54561817518;11372;48051.5194848061;49156.50843491662;49160.26259606037;49824.50175498199;51430.48569514257;63555;65838.34161658514;67101.993470097;67151;67179.32820671731;12279.815802763145;12374.566890158801;12380.504779808707;12494.937525312314;12648.810267846175;13478.730425391634;14071.507497237548;14647.12117272967;15219.239038047832;15232.086074835544;11654.883451165719;13074.932566175;13710.38303276375;13814.65463363424;13859.606194226182;12610.810837837256;13600.251986140856;13601.523946661819;13607;13673.589792306248;12170.148089633994;13355.198688078746;13484.258365789976;13502.797458037941;13518.459261629818;8916.73249802507;9313.720588382359;9498.525073746147;9616.90383096188;9640.843098828185;10014.353045179763;10750.59808612459;11383.146264030065;11395.544178232778;11435.885641143483 09:30:00 INFO - PROCESS | 1918 | __end_tp_report 09:30:00 INFO - PROCESS | 1918 | __start_cc_report 09:30:00 INFO - PROCESS | 1918 | _x_x_mozilla_cycle_collect,2045 09:30:00 INFO - PROCESS | 1918 | __end_cc_report 09:30:00 INFO - PROCESS | 1918 | __startTimestamp1492187400849__endTimestamp 09:30:00 INFO - PROCESS | 1918 | 09:30:00 INFO - PROCESS | 1918 | ------- Summary: start ------- 09:30:00 INFO - PROCESS | 1918 | Number of tests: 6 09:30:00 INFO - PROCESS | 1918 | 09:30:00 INFO - PROCESS | 1918 | [#0] dojo.html Cycles:165 Average:5674.61 Median:5823.94 stddev:678.63 (11.7%) stddev-sans-first:641.58 09:30:00 INFO - PROCESS | 1918 | Values: 2779.6 3298.4 3330.5 3331.4 3335.9 4678.1 5576.6 5667.7 5685.3 5696.4 4817.1 5499.4 5599.8 5651.3 5660.4 5142.1 5151.4 5244.1 5252.3 5253.4 4228.9 5095.7 5104.9 5132.9 5265.2 4351.7 5151.8 5165.4 5171.9 5221.8 5209.8 5220.9 5223.9 5238.1 5251.2 4957.6 5128.4 5148.7 5157.3 5158.7 5529.9 5536.8 5541.2 5667.1 5695.7 6199.1 6227.9 6260.1 6266.8 6269.9 6216.5 6287.1 6292.6 6304.0 6304.6 5531.7 5653.1 5662.0 5808.0 5823.9 5502.5 5929.8 6286.9 6365.8 6393.2 5182.5 6095.1 6115.8 6165.3 6208.5 6237.3 6242.9 6269.2 6292.9 6296.8 5974.9 5979.7 5982.2 6201.8 6230.8 6144.2 6182.2 6195.8 6202.0 6225.2 4989.7 5029.8 5031.3 5031.8 5061.3 4375.4 4472.7 4475.4 4479.6 4491.1 5658.2 5678.5 5742.5 5789.5 5802.4 6347.7 6350.5 6354.5 6358.9 6364.6 5763.8 5801.9 5806.2 5815.0 5846.7 5269.9 5352.2 5397.0 5400.0 5482.6 6312.7 6372.0 6383.5 6395.3 6405.4 6302.6 6315.4 6320.6 6330.3 6334.3 6213.2 6314.0 6317.1 6359.7 6397.0 6124.6 6154.3 6210.2 6226.1 6248.3 5921.0 6136.8 6276.7 6298.8 6313.9 5795.0 5874.3 5887.9 5926.5 5969.7 5073.6 5906.9 5912.7 5933.9 5935.1 5796.3 5811.8 5824.7 5830.9 5837.1 5242.5 5557.1 5914.8 5969.5 6028.9 5673.7 5866.4 5897.4 5904.3 5917.7 09:30:00 INFO - PROCESS | 1918 | 09:30:00 INFO - PROCESS | 1918 | [#1] ext.html Cycles:180 Average:53156.51 Median:16552.33 stddev:107181.85 (647.5%) stddev-sans-first:107410.15 09:30:00 INFO - PROCESS | 1918 | Values: 695.6 743.2 760.6 818.5 829.5 27650.3 27983.6 28503.9 28528.0 28585.7 27884.6 31735.5 40395.0 42028.0 42273.4 7898.8 9113.0 9130.9 9144.6 9155.8 7144.9 7848.2 8925.2 8947.5 9031.4 7301.9 10656.7 10857.0 10865.2 10866.1 10721.6 10762.5 10772.9 10779.2 10787.0 10600.7 10666.3 10687.1 10697.8 10698.3 37475.3 37606.0 37638.2 37647.0 37683.0 3783.6 3797.1 3841.6 3860.0 3894.8 25863.7 26549.0 27266.5 27572.7 28536.1 3045.0 3106.7 3179.3 3247.6 3281.8 339996.0 341905.3 342809.0 343126.0 343235.0 51973.0 52121.7 52149.7 52257.7 52322.5 42620.8 54421.0 54460.2 54548.7 54672.5 14595.0 14858.7 14892.6 14895.3 14920.2 12834.0 12855.9 12858.2 12863.9 12878.6 10867.0 11091.6 12215.3 12809.7 12872.0 4736.2 4895.3 5486.1 5666.8 5691.2 971.2 1096.9 1122.3 1140.5 1169.9 26324.5 26459.7 27050.5 27094.9 27100.9 1223.0 1251.3 1262.7 1270.8 1274.3 13661.7 13704.0 13925.8 13965.3 13972.5 465863.0 466129.0 466165.0 466789.0 467745.0 51537.7 55412.0 55429.8 55439.7 55444.7 1418.0 1440.4 1444.8 1445.1 1449.2 25159.6 25865.0 25896.1 25994.8 26055.0 46253.0 46349.0 46423.5 46532.8 46546.5 390892.0 393127.0 393161.0 393593.0 394281.0 29233.9 30059.7 31011.0 31062.8 34623.0 37425.3 38041.7 38324.2 38868.4 39064.0 21949.0 26856.7 27450.6 27695.6 28121.2 25388.0 27039.7 27261.5 28012.7 28116.0 31223.7 31484.0 32096.4 32318.4 32436.8 25193.7 25995.7 28320.7 28438.7 28834.9 21280.4 22267.7 22823.0 22867.7 22906.0 09:30:00 INFO - PROCESS | 1918 | 09:30:00 INFO - PROCESS | 1918 | [#2] jquery.html Cycles:180 Average:42851.79 Median:22518.10 stddev:121467.04 (539.4%) stddev-sans-first:121765.92 09:30:00 INFO - PROCESS | 1918 | Values: 377.2 381.9 404.1 410.6 419.6 12940.5 14115.6 17594.0 20725.0 21231.5 21618.5 21723.8 22172.4 22351.0 22685.2 6346.5 6467.5 6494.6 6585.5 6673.9 6469.2 6494.7 6597.4 6598.8 6608.9 5562.6 6439.6 6605.7 6617.0 6618.5 6319.9 6496.2 6584.8 6599.9 6600.3 5489.2 6424.9 6429.4 6429.5 6436.0 23528.0 24431.6 25854.0 26151.3 26387.7 2863.7 2890.3 2894.4 2923.1 2940.2 15329.5 15559.1 15807.2 15851.6 15876.4 1724.7 2000.6 2012.8 2017.9 2054.1 171540.0 173925.0 176266.5 177530.0 179361.2 19352.7 19369.7 19637.7 19679.0 19879.5 31097.0 31367.0 31500.8 31562.8 31605.8 9621.8 9871.5 10009.3 10199.4 10232.4 7166.7 8108.4 8324.8 8401.8 8605.1 8949.0 9087.1 9141.5 9208.1 9241.2 3285.7 3725.3 3874.4 3874.5 3894.8 975.7 979.5 983.8 1011.4 1038.7 19959.8 20075.8 20137.9 20194.9 20262.0 985.3 993.4 997.0 1005.5 1011.4 9661.6 10019.0 10226.4 10461.3 10517.3 609375.0 687912.0 742198.0 764790.0 787516.0 154574.0 154728.7 155570.0 160646.2 160762.0 1148.5 1206.1 1254.3 1254.4 1259.7 19346.4 19387.5 20090.6 20117.2 20178.6 65659.0 66352.0 66528.7 66871.7 67469.0 92792.5 93298.5 93439.5 94714.6 95759.0 10993.4 11347.8 11401.3 11490.8 11635.6 12417.2 12579.0 12928.6 12950.8 13018.2 9203.8 10757.7 10758.1 10955.6 10995.6 9329.7 10101.9 10111.7 10173.2 10265.8 9163.6 11148.3 11396.4 11419.4 11490.8 9406.9 10209.5 11327.4 11454.3 11633.5 10997.5 11019.2 11259.6 11261.2 11284.3 09:30:00 INFO - PROCESS | 1918 | 09:30:00 INFO - PROCESS | 1918 | [#3] mootools.html Cycles:180 Average:6627.31 Median:3141.93 stddev:4173.75 (132.8%) stddev-sans-first:4167.55 09:30:00 INFO - PROCESS | 1918 | Values: 1481.2 1494.6 1533.5 1556.6 1558.7 10679.5 11271.5 11300.3 11303.5 11319.4 10494.4 10826.1 11068.2 11083.3 11089.7 2325.2 2356.9 2364.0 2364.1 2364.6 2278.9 2350.9 2369.8 2375.2 2378.3 2312.1 2370.4 2381.4 2390.2 2390.8 2146.0 2209.6 2324.2 2324.2 2356.8 2349.4 2352.1 2354.1 2357.8 2357.9 10882.9 10959.0 10993.7 10993.9 11020.0 5362.9 5443.5 5608.0 5628.9 5629.4 2791.0 3492.9 3557.8 3575.5 3585.6 1101.5 1109.0 1168.3 1189.9 1196.6 15515.1 15542.8 15673.9 15686.0 15705.6 3715.3 3779.8 4431.9 4529.3 4606.0 11541.8 11550.0 11574.5 11584.9 11594.5 5621.4 5622.8 5633.2 5637.2 5677.1 4990.3 5038.9 5111.6 5200.5 5260.3 3741.3 3798.6 3804.7 3857.0 3874.3 893.2 926.9 961.5 969.7 970.4 925.3 931.2 939.8 952.8 991.3 6067.3 6260.3 6537.0 6562.9 6634.9 957.7 960.8 984.6 1001.8 1017.3 4173.7 4241.5 4242.3 4338.8 4357.9 12818.9 13518.4 13521.3 13540.0 13545.8 10934.1 11266.9 13454.0 13479.1 13537.4 9647.3 10045.9 10072.6 10088.7 10098.0 6778.4 6888.1 7031.4 7039.8 7071.0 11915.8 12189.6 12285.6 12289.1 12339.0 12120.2 12165.5 12314.1 12414.3 12448.2 8219.9 8281.1 8358.0 8393.0 8401.9 9990.1 10081.1 10185.6 10196.4 10220.3 8641.7 8713.7 8716.3 8720.2 8772.6 6980.9 8461.5 8543.9 8552.5 8584.6 7732.7 7773.7 7868.5 7869.9 7876.3 5913.4 5939.0 6051.8 6063.3 6094.7 5949.4 6009.5 6062.3 6134.8 6144.9 09:30:00 INFO - PROCESS | 1918 | 09:30:00 INFO - PROCESS | 1918 | [#4] prototype.html Cycles:180 Average:4413.45 Median:4738.18 stddev:1234.54 (26.1%) stddev-sans-first:1200.26 09:30:00 INFO - PROCESS | 1918 | Values: 377.4 382.1 382.5 389.9 392.3 3851.7 3895.1 4519.3 4734.1 4988.7 3833.9 4572.5 4880.5 4881.3 4925.1 4123.5 4165.8 4193.7 4206.5 4263.3 3979.1 4145.3 4182.8 4227.1 4335.5 3434.4 4065.5 4169.8 4228.9 4232.7 3937.6 4134.0 4165.4 4177.2 4303.9 3845.7 3846.5 3874.4 3876.2 3909.0 4159.3 4306.4 4682.2 4944.5 4953.1 3848.1 3958.9 4256.5 4394.1 4828.9 4703.6 5172.5 5299.9 5323.4 5375.9 3802.9 4119.9 4177.4 4310.7 4321.4 4305.2 4662.4 5036.7 5445.7 5458.8 3955.4 4085.8 4351.5 4372.0 4374.3 3677.2 4048.1 4066.6 4078.3 4542.0 3975.5 4460.8 4804.5 4851.7 4940.4 4782.6 4813.0 5103.9 5270.0 5299.7 1380.7 1464.3 1465.8 1482.3 1508.2 1086.5 1139.9 1142.8 1146.6 1160.8 4443.0 4670.8 5007.4 5092.9 5157.7 5066.5 5568.6 5611.2 5615.6 5678.0 4738.9 4844.2 4965.5 4997.8 5055.3 2597.6 2633.0 2647.9 2881.7 2897.3 4944.6 5120.9 5427.9 5777.4 5866.7 4426.7 4847.0 5081.6 5556.0 5714.5 5591.4 5606.3 5611.7 5662.4 5669.7 4870.5 5410.7 5441.9 5487.8 5521.4 4526.7 5170.4 5180.8 5247.9 5496.3 5315.6 5760.8 5765.7 5771.5 5824.7 3787.6 3799.0 4095.9 4412.6 4419.4 4756.5 5286.8 5497.4 5523.8 5582.2 4757.9 5317.5 5343.4 5349.6 5360.0 4742.9 5190.9 5352.8 5388.6 5468.0 4658.0 4737.5 4895.7 5189.8 5218.3 4571.0 5126.9 5336.6 5356.5 5378.0 4077.7 5209.7 5213.4 5214.5 5227.5 09:30:00 INFO - PROCESS | 1918 | 09:30:00 INFO - PROCESS | 1918 | [#5] yui.html Cycles:180 Average:19693.92 Median:3584.84 stddev:27850.52 (776.9%) stddev-sans-first:27892.14 09:30:00 INFO - PROCESS | 1918 | Values: 702.7 707.0 741.1 766.0 859.4 14882.0 14959.2 15267.2 15350.8 15616.3 12837.2 12997.9 14383.0 14522.8 14540.9 934.2 940.6 944.2 946.0 946.6 926.7 926.8 958.1 964.2 974.2 998.3 1003.3 1005.4 1007.3 1009.4 991.5 997.0 999.6 1001.2 1001.3 743.3 743.5 743.8 743.8 747.3 15316.7 15638.6 15708.6 15801.3 16072.4 13866.9 14033.2 14039.4 14177.2 14192.3 11584.9 11705.1 11978.5 12129.3 12443.4 3552.6 3617.1 3621.1 3627.8 3676.3 55578.3 82527.0 83564.0 107465.5 111309.4 10390.4 10626.2 10842.3 10910.9 11138.0 21534.9 22199.1 22204.9 22213.9 22248.3 7010.0 7829.1 7911.5 7932.6 7955.2 4224.6 4225.4 4303.2 4356.6 4389.8 3408.5 3506.2 3511.0 3512.0 3524.7 621.3 637.1 646.2 661.3 661.8 659.6 685.6 687.2 711.9 723.6 9597.7 9991.0 10130.6 10131.4 10196.7 610.0 637.7 673.0 686.8 689.3 4694.2 4903.9 4904.1 4916.5 4918.7 104879.0 105670.5 107221.5 108036.5 111193.0 73530.3 79926.2 88399.6 88599.1 89144.0 62309.0 69470.3 69539.6 75540.6 77085.2 9036.1 11003.0 11265.1 11359.5 11372.0 48051.5 49156.5 49160.3 49824.5 51430.5 63555.0 65838.3 67102.0 67151.0 67179.3 12279.8 12374.6 12380.5 12494.9 12648.8 13478.7 14071.5 14647.1 15219.2 15232.1 11654.9 13074.9 13710.4 13814.7 13859.6 12610.8 13600.3 13601.5 13607.0 13673.6 12170.1 13355.2 13484.3 13502.8 13518.5 8916.7 9313.7 9498.5 9616.9 9640.8 10014.4 10750.6 11383.1 11395.5 11435.9 09:30:00 INFO - PROCESS | 1918 | -------- Summary: end -------- 09:30:00 INFO - PROCESS | 1918 | 09:30:01 INFO - TEST-INFO | 1918: exit 0 09:30:01 INFO - TEST-OK | dromaeo_css | took 1120064ms 09:30:01 INFO - TEST-START | kraken 09:30:01 INFO - operating with platform_type : mac_ 09:30:01 INFO - Initialising browser for kraken test... 09:30:01 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpReTzhp/profile http://localhost:52336/getInfo.html) 09:30:04 INFO - PROCESS | 1923 | __metrics Screen width/height:1600/1200 09:30:04 INFO - PROCESS | 1923 | colorDepth:24 09:30:04 INFO - PROCESS | 1923 | Browser inner width/height: 1024/689 09:30:04 INFO - PROCESS | 1923 | __metrics 09:30:04 INFO - PROCESS | 1923 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:30:04 INFO - PROCESS | 1923 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:30:05 INFO - TEST-INFO | 1923: exit 0 09:30:05 INFO - Browser initialized. 09:30:05 INFO - Running cycle 1/1 for kraken test... 09:30:05 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpReTzhp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:30:17 INFO - PROCESS | 1924 | RSS: Main: 210472960 09:30:17 INFO - PROCESS | 1924 | 09:30:19 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:52336/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52336/tests/kraken/driver.html?testName=audio-beat-detection) 09:30:20 INFO - PROCESS | 1924 | RSS: Main: 264855552 09:30:20 INFO - PROCESS | 1924 | 09:30:23 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:52336/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52336/tests/kraken/driver.html?testName=audio-dft) 09:30:24 INFO - PROCESS | 1924 | RSS: Main: 277848064 09:30:24 INFO - PROCESS | 1924 | 09:30:27 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:52336/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52336/tests/kraken/driver.html?testName=audio-fft) 09:30:27 INFO - PROCESS | 1924 | RSS: Main: 374120448 09:30:27 INFO - PROCESS | 1924 | 09:30:29 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:52336/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52336/tests/kraken/driver.html?testName=audio-oscillator) 09:30:30 INFO - PROCESS | 1924 | RSS: Main: 277921792 09:30:30 INFO - PROCESS | 1924 | 09:30:32 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:52336/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52336/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:30:33 INFO - PROCESS | 1924 | RSS: Main: 279367680 09:30:33 INFO - PROCESS | 1924 | 09:30:36 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:52336/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52336/tests/kraken/driver.html?testName=imaging-darkroom) 09:30:36 INFO - PROCESS | 1924 | RSS: Main: 318177280 09:30:36 INFO - PROCESS | 1924 | 09:30:39 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:52336/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52336/tests/kraken/driver.html?testName=imaging-desaturate) 09:30:40 INFO - PROCESS | 1924 | RSS: Main: 320065536 09:30:40 INFO - PROCESS | 1924 | 09:30:43 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:52336/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52336/tests/kraken/driver.html?testName=json-parse-financial) 09:30:44 INFO - PROCESS | 1924 | RSS: Main: 320536576 09:30:44 INFO - PROCESS | 1924 | 09:30:45 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:52336/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52336/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:30:46 INFO - PROCESS | 1924 | RSS: Main: 296554496 09:30:46 INFO - PROCESS | 1924 | 09:30:48 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:52336/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52336/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:30:48 INFO - PROCESS | 1924 | RSS: Main: 300163072 09:30:48 INFO - PROCESS | 1924 | 09:30:51 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:52336/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52336/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:30:52 INFO - PROCESS | 1924 | RSS: Main: 303566848 09:30:52 INFO - PROCESS | 1924 | 09:30:54 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:52336/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52336/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:30:55 INFO - PROCESS | 1924 | RSS: Main: 308371456 09:30:55 INFO - PROCESS | 1924 | 09:30:58 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:52336/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52336/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:30:59 INFO - PROCESS | 1924 | RSS: Main: 309821440 09:30:59 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:52336/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52336/tests/kraken/driver.html?testName=ai-astar) 09:31:01 INFO - PROCESS | 1924 | RSS: Main: 354611200 09:31:01 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | __start_tp_report 09:31:01 INFO - PROCESS | 1924 | _x_x_mozilla_page_load 09:31:01 INFO - PROCESS | 1924 | _x_x_mozilla_page_load_details 09:31:01 INFO - PROCESS | 1924 | |i|pagename|runs| 09:31:01 INFO - PROCESS | 1924 | |0;ai-astar;105;115;115;115;115;116;115;115;114;114 09:31:01 INFO - PROCESS | 1924 | |1;audio-beat-detection;147;107;113;147;111;111;111;110;131;111 09:31:01 INFO - PROCESS | 1924 | |2;audio-dft;186;188;182;183;187;185;183;185;186;186 09:31:01 INFO - PROCESS | 1924 | |3;audio-fft;82;62;60;93;62;62;61;61;77;62 09:31:01 INFO - PROCESS | 1924 | |4;audio-oscillator;85;70;86;73;69;68;81;67;68;82 09:31:01 INFO - PROCESS | 1924 | |5;imaging-gaussian-blur;116;136;88;88;87;88;88;87;88;86 09:31:01 INFO - PROCESS | 1924 | |6;imaging-darkroom;95;96;95;95;102;95;95;105;153;99 09:31:01 INFO - PROCESS | 1924 | |7;imaging-desaturate;86;84;85;86;83;87;84;84;87;85 09:31:01 INFO - PROCESS | 1924 | |8;json-parse-financial;62;58;60;60;63;64;64;62;63;65 09:31:01 INFO - PROCESS | 1924 | |9;json-stringify-tinderbox;53;60;65;66;61;60;80;84;85;85 09:31:01 INFO - PROCESS | 1924 | |10;stanford-crypto-aes;87;89;86;87;84;85;82;90;89;114 09:31:01 INFO - PROCESS | 1924 | |11;stanford-crypto-ccm;140;137;136;137;132;137;137;140;154;138 09:31:01 INFO - PROCESS | 1924 | |12;stanford-crypto-pbkdf2;168;166;169;149;231;227;214;163;212;229 09:31:01 INFO - PROCESS | 1924 | |13;stanford-crypto-sha256-iterative;65;65;65;65;66;67;64;64;64;65 09:31:01 INFO - PROCESS | 1924 | __end_tp_report 09:31:01 INFO - PROCESS | 1924 | __start_cc_report 09:31:01 INFO - PROCESS | 1924 | _x_x_mozilla_cycle_collect,2178 09:31:01 INFO - PROCESS | 1924 | __end_cc_report 09:31:01 INFO - PROCESS | 1924 | __startTimestamp1492187461276__endTimestamp 09:31:01 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | ------- Summary: start ------- 09:31:01 INFO - PROCESS | 1924 | Number of tests: 14 09:31:01 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | [#0] ai-astar Cycles:10 Average:113.90 Median:115.00 stddev:3.18 (2.8%) stddev-sans-first:0.60 09:31:01 INFO - PROCESS | 1924 | Values: 105.0 115.0 115.0 115.0 115.0 116.0 115.0 115.0 114.0 114.0 09:31:01 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | [#1] audio-beat-detection Cycles:10 Average:119.90 Median:112.00 stddev:15.71 (14.0%) stddev-sans-first:13.25 09:31:01 INFO - PROCESS | 1924 | Values: 147.0 107.0 113.0 147.0 111.0 111.0 111.0 110.0 131.0 111.0 09:31:01 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | [#2] audio-dft Cycles:10 Average:185.10 Median:186.00 stddev:1.91 (1.0%) stddev-sans-first:2.00 09:31:01 INFO - PROCESS | 1924 | Values: 186.0 188.0 182.0 183.0 187.0 185.0 183.0 185.0 186.0 186.0 09:31:01 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | [#3] audio-fft Cycles:10 Average:68.20 Median:62.00 stddev:11.58 (18.7%) stddev-sans-first:11.16 09:31:01 INFO - PROCESS | 1924 | Values: 82.0 62.0 60.0 93.0 62.0 62.0 61.0 61.0 77.0 62.0 09:31:01 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | [#4] audio-oscillator Cycles:10 Average:74.90 Median:77.00 stddev:7.69 (10.0%) stddev-sans-first:7.24 09:31:01 INFO - PROCESS | 1924 | Values: 85.0 70.0 86.0 73.0 69.0 68.0 81.0 67.0 68.0 82.0 09:31:01 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | [#5] imaging-gaussian-blur Cycles:10 Average:95.20 Median:88.00 stddev:16.92 (19.2%) stddev-sans-first:16.18 09:31:01 INFO - PROCESS | 1924 | Values: 116.0 136.0 88.0 88.0 87.0 88.0 88.0 87.0 88.0 86.0 09:31:01 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | [#6] imaging-darkroom Cycles:10 Average:103.00 Median:95.00 stddev:17.92 (18.9%) stddev-sans-first:18.77 09:31:01 INFO - PROCESS | 1924 | Values: 95.0 96.0 95.0 95.0 102.0 95.0 95.0 105.0 153.0 99.0 09:31:01 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:1.37 (1.6%) stddev-sans-first:1.41 09:31:01 INFO - PROCESS | 1924 | Values: 86.0 84.0 85.0 86.0 83.0 87.0 84.0 84.0 87.0 85.0 09:31:01 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:63.00 stddev:2.18 (3.5%) stddev-sans-first:2.32 09:31:01 INFO - PROCESS | 1924 | Values: 62.0 58.0 60.0 60.0 63.0 64.0 64.0 62.0 63.0 65.0 09:31:01 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:73.00 stddev:12.28 (16.8%) stddev-sans-first:11.40 09:31:01 INFO - PROCESS | 1924 | Values: 53.0 60.0 65.0 66.0 61.0 60.0 80.0 84.0 85.0 85.0 09:31:01 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:87.00 stddev:9.02 (10.4%) stddev-sans-first:9.53 09:31:01 INFO - PROCESS | 1924 | Values: 87.0 89.0 86.0 87.0 84.0 85.0 82.0 90.0 89.0 114.0 09:31:01 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | [#11] stanford-crypto-ccm Cycles:10 Average:138.80 Median:137.50 stddev:5.79 (4.2%) stddev-sans-first:6.12 09:31:01 INFO - PROCESS | 1924 | Values: 140.0 137.0 136.0 137.0 132.0 137.0 137.0 140.0 154.0 138.0 09:31:01 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.80 Median:213.00 stddev:32.43 (15.2%) stddev-sans-first:33.13 09:31:01 INFO - PROCESS | 1924 | Values: 168.0 166.0 169.0 149.0 231.0 227.0 214.0 163.0 212.0 229.0 09:31:01 INFO - PROCESS | 1924 | 09:31:01 INFO - PROCESS | 1924 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.00 stddev:0.94 (1.5%) stddev-sans-first:1.00 09:31:01 INFO - PROCESS | 1924 | Values: 65.0 65.0 65.0 65.0 66.0 67.0 64.0 64.0 64.0 65.0 09:31:01 INFO - PROCESS | 1924 | -------- Summary: end -------- 09:31:01 INFO - PROCESS | 1924 | 09:31:02 INFO - TEST-INFO | 1924: exit 0 09:31:02 INFO - TEST-OK | kraken | took 60477ms 09:31:02 INFO - SUITE-END | took 1180s 09:31:02 INFO - Completed test suite (00:19:40) 09:31:02 INFO - javascript benchmark 09:31:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7485.870994081635, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2779.583062540619, 3298.4433976357154, 3330.500424936259, 3331.4336562784324, 3335.933281334375, 4678.064387122577, 5576.609637325389, 5667.716614169296, 5685.289338832651, 5696.3733989261145, 4817.108834865552, 5499.3675727291375, 5599.776008959637, 5651.32184137903, 5660.377358490573, 5142.125838607436, 5151.356080489939, 5244.082285600024, 5252.343457067866, 5253.4221235664045, 4228.936565951513, 5095.6942583444925, 5104.923426148611, 5132.8973420531565, 5265.157574788015, 4351.71713838601, 5151.7681704323395, 5165.431802501722, 5171.922421163686, 5221.817236396727, 5209.765560549784, 5220.895582088337, 5223.895522089575, 5238.109521381355, 5251.159814429673, 4957.603391728653, 5128.435872054071, 5148.6910785353, 5157.252198431244, 5158.69047857126, 5529.861753456155, 5536.750846211889, 5541.168824676291, 5667.121596152561, 5695.658260504383, 6199.132121502993, 6227.875442491125, 6260.09228661846, 6266.843328916768, 6269.937300626981, 6216.502679785606, 6287.088372186053, 6292.590981586182, 6303.968480157616, 6304.621722696653, 5531.723413829292, 5653.123765816305, 5662.009148399096, 5807.983602869481, 5823.941760582425, 5502.477264659851, 5929.762809487572, 6286.937130628635, 6365.809025729235, 6393.168888044617, 5182.481751824787, 6095.116208149793, 6115.816525504242, 6165.290991536031, 6208.4722798562625, 6237.345078766755, 6242.906356404657, 6269.185005949289, 6292.937070629327, 6296.8425789354715, 5974.9402505974385, 5979.701014949235, 5982.162497250389, 6201.7519299228425, 6230.7819226327765, 6144.218176728788, 6182.165407669997, 6195.752169913243, 6201.976673848854, 6225.159603453566, 4989.650724449254, 5029.7736601853, 5031.295618613397, 5031.77357018935, 5061.2661164131, 4375.387445757597, 4472.664550158686, 4475.351074094303, 4479.619232365286, 4491.079328737601, 5658.151277308354, 5678.488935995781, 5742.54059675225, 5789.507891829157, 5802.448767367177, 6347.714352854133, 6350.4602108820345, 6354.52341074412, 6358.904616430757, 6364.64994425311, 5763.7982670606325, 5801.906340654763, 5806.21616081832, 5815.040518314515, 5846.678432686158, 5269.894602107936, 5352.170413585901, 5396.9730151348995, 5400.0, 5482.5888058394985, 6312.747490100344, 6372.012338087604, 6383.5212359074185, 6395.328490508429, 6405.423511883952, 6302.621842689453, 6315.400036996478, 6320.5891617044745, 6330.335314791881, 6334.334894835976, 6213.2233470816145, 6313.96843015782, 6317.147185130132, 6359.7456101755415, 6397.0, 6124.625058545555, 6154.261488621215, 6210.192674952227, 6226.128342032029, 6248.343923887926, 5920.970395147997, 6136.846578835565, 6276.6547839869245, 6298.842528936813, 6313.873722525432, 5795.043817770021, 5874.324452687995, 5887.882242355215, 5926.525877929842, 5969.70151492415, 5073.594112471067, 5906.881862362643, 5912.67480288571, 5933.910991335047, 5935.080062590306, 5796.275465566804, 5811.79658711943, 5824.708764561839, 5830.912536312044, 5837.066069429041, 5242.501962313574, 5557.055300598827, 5914.763409463573, 5969.492593129633, 6028.8794224116145, 5673.687947162945, 5866.384029676822, 5897.351291358033, 5904.3210030845285, 5917.733701983335], "value": 5630.006185706187, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.5652717051843, 743.182499250824, 760.562676461035, 818.4679958027282, 829.5396055189375, 27650.308742281428, 27983.580246296322, 28503.857480712595, 28528.001519946807, 28585.714142858564, 27884.581731273996, 31735.52396714051, 40395.0, 42028.0, 42273.36589951153, 7898.802529936769, 9113.043130471304, 9130.908690913073, 9144.634214631407, 9155.771105722377, 7144.85710285794, 7848.215178482134, 8925.241354484877, 8947.463152210888, 9031.412958157698, 7301.926980730178, 10656.733581660443, 10857.0, 10865.239433239676, 10866.130709543257, 10721.571137154504, 10762.461876906202, 10772.892271077306, 10779.245452818306, 10787.029167374974, 10600.734981625445, 10666.306690065121, 10687.091597214247, 10697.786044279148, 10698.30461020039, 37475.25049499022, 37606.0, 37638.24723505515, 37647.0, 37683.0, 3783.6405541473514, 3797.1456422305037, 3841.5774264830848, 3860.0156959975025, 3894.844206231747, 25863.74136258651, 26549.0, 27266.45467090647, 27572.724272757416, 28536.14391568256, 3044.9543256851166, 3106.6582675905634, 3179.2528755742383, 3247.6102867655795, 3281.803091814475, 339996.0, 341905.2904735485, 342809.0, 343125.9999999975, 343235.0, 51973.0, 52121.7393913028, 52149.7392513035, 52257.7387113062, 52322.47677523252, 42620.78689606594, 54421.0, 54460.183097253575, 54548.72725636346, 54672.45327546674, 14594.978351515505, 14858.702825943421, 14892.553223403314, 14895.255237238094, 14920.179390133648, 12834.0, 12855.871441285655, 12858.164219325714, 12863.935680321725, 12878.613641590768, 10867.021968022758, 11091.611793587346, 12215.26708397499, 12809.679758006125, 12872.034597405232, 4736.230962326923, 4895.335585900811, 5486.147203454181, 5666.801661941895, 5691.203231547586, 971.1939090554822, 1096.9396683182342, 1122.337820685783, 1140.4582823158933, 1169.859616845967, 26324.473510529682, 26459.73540264573, 27050.458990820465, 27094.916203351644, 27100.86449567779, 1222.9877701222874, 1251.2680082152042, 1262.671705356614, 1270.7712611729792, 1274.343712987812, 13661.658458538417, 13704.0, 13925.79111313311, 13965.301734913417, 13972.510962116481, 465863.0, 466129.0, 466165.0, 466789.0, 467745.0, 51537.7423112882, 55412.0, 55429.78280868801, 55439.72280138574, 55444.72277638586, 1418.049906562585, 1440.3806363263689, 1444.8441247002565, 1445.096814490922, 1449.159487497249, 25159.622605660563, 25865.0, 25896.093636722624, 25994.830232639197, 26055.0, 46253.0, 46349.0, 46423.53576464192, 46532.83667908194, 46546.53453465422, 390892.0, 393127.0, 393161.0, 393593.0, 394281.0, 29233.85383073071, 30059.69940300569, 31011.0, 31062.844685776425, 34623.0, 37425.25149497049, 38041.66854160202, 38324.233515330096, 38868.41697374598, 39064.023399415244, 21949.0, 26856.731432686203, 27450.588241176, 27695.58456623112, 28121.172123812707, 25387.98448062057, 27039.729602703723, 27261.454770904867, 28012.719872801827, 28116.0, 31223.687763122078, 31484.0, 32096.35807283888, 32318.35363292674, 32436.83781581077, 25193.748062519142, 25995.74004260009, 28320.71679283263, 28438.715612843607, 28834.855825720737, 21280.36158915235, 22267.66598501056, 22823.0, 22867.656985144902, 22906.0], "value": 17966.882935844373, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.1589355736707, 381.9274337875805, 404.13111809609336, 410.60581841432224, 419.6391103650861, 12940.547080852171, 14115.647108822284, 17594.0, 20725.0, 21231.469213269676, 21618.459538511546, 21723.782762172374, 22172.445688857788, 22351.0, 22685.20601778938, 6346.524010699193, 6467.450266727322, 6494.5778524396, 6585.473162147016, 6673.93326066738, 6469.191351081115, 6494.707738151795, 6597.4392176664805, 6598.769043083493, 6608.900866487006, 5562.638428502155, 6439.645819479927, 6605.70274337656, 6617.040529123275, 6618.470522358224, 6319.873602527924, 6496.155499784998, 6584.8024559263295, 6599.868002639944, 6600.273969863311, 5489.204065410533, 6424.93575064248, 6429.38920802523, 6429.517786166056, 6436.0, 23528.0, 24431.63352549713, 25854.0, 26151.346216344555, 26387.73612263872, 2863.727945845162, 2890.262982939358, 2894.3921776426973, 2923.093840909325, 2940.1767505098605, 15329.463468778651, 15559.066456012675, 15807.209639518092, 15851.60370990723, 15876.36494540217, 1724.6981778188767, 2000.614658616617, 2012.818846303825, 2017.939461816148, 2054.0551346380685, 171540.0, 173925.0, 176266.47467050716, 177530.0, 179361.20638793707, 19352.710039293353, 19369.709454358195, 19637.70096662779, 19679.0, 19879.503012424517, 31097.0, 31367.0, 31500.842495787376, 31562.842185789385, 31605.84197079, 9621.807563848824, 9871.506424678737, 10009.349392289478, 10199.439030853375, 10232.437215953194, 7166.677499512431, 8108.39187060961, 8324.833503329899, 8401.789955251046, 8605.063860631433, 8948.955255223813, 9087.137551671203, 9141.451512909247, 9208.12522810332, 9241.21449676785, 3285.655006224312, 3725.2549490102087, 3874.3994680824517, 3874.5350557932666, 3894.844206231719, 975.6633961283346, 979.4759803505091, 983.8032393521324, 1011.3780025284398, 1038.7039693687263, 19959.800401996086, 20075.799242007393, 20137.899310503355, 20194.89902550478, 20262.0, 985.2955137077024, 993.3841018568389, 997.0, 1005.4671024357102, 1011.3881101933224, 9661.565229564687, 10018.996200759877, 10226.437545934896, 10461.26771126029, 10517.316374435637, 609375.0, 687912.0, 742198.0, 764790.0, 787516.0, 154574.0, 154728.67906981404, 155570.0, 160646.19676901776, 160762.0, 1148.5233628044434, 1206.1376116077047, 1254.2599866079056, 1254.3602762591145, 1259.716563773162, 19346.4196074118, 19387.51531211731, 20090.598188035867, 20117.19531218735, 20178.59642807165, 65659.0302290932, 66352.0, 66528.6673566629, 66871.66564167148, 67469.0, 92792.53603731938, 93298.53695534846, 93439.53280233555, 94714.57928131221, 95759.0, 10993.395363254778, 11347.829782553103, 11401.315921044765, 11490.82763758561, 11635.592754253561, 12417.19288246261, 12579.0, 12928.612141635766, 12950.80573791375, 13018.15382000167, 9203.769905752142, 10757.73105672333, 10758.13934885223, 10955.56177752881, 10995.61515346991, 9329.673461428818, 10101.89898101039, 10111.696649100539, 10173.23700722434, 10265.794684106128, 9163.633454662006, 11148.275362101438, 11396.373199474108, 11419.371934543347, 11490.82763758561, 9406.858897116412, 10209.489525523546, 11327.433628318386, 11454.255473394203, 11633.53465861356, 10997.505112269808, 11019.228653994143, 11259.605913793306, 11261.21171517986, 11284.250941104143], "value": 11297.911890933194, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1481.1557412275004, 1494.5889880282914, 1533.4786172701279, 1556.571942715753, 1558.7272227360208, 10679.466026698663, 11271.492782824773, 11300.265482743616, 11303.547858085676, 11319.434028298583, 10494.37033777974, 10826.079783218416, 11068.169887258447, 11083.334999900011, 11089.667309980694, 2325.16294134112, 2356.9410764730846, 2364.0189321431585, 2364.113457453455, 2364.6216605343147, 2278.908843646252, 2350.894209760565, 2369.8222633302485, 2375.228050883461, 2378.2508509819436, 2312.063614236237, 2370.383700237942, 2381.3570336009266, 2390.247072172269, 2390.8445951013214, 2145.9892700536475, 2209.591225623265, 2324.2097686786406, 2324.2213858357472, 2356.823238257129, 2349.4361353275262, 2352.082687751761, 2354.093673935547, 2357.823163262762, 2357.8821058946983, 10882.911708829133, 10959.0, 10993.670189894316, 10993.945030274877, 11020.0, 5362.892742145174, 5443.482869127427, 5608.0, 5628.9305032043785, 5629.437056294379, 2790.9581356279673, 3492.89521314361, 3557.8221088945706, 3575.5173051637958, 3585.587657419404, 1101.531848964195, 1108.9778204435866, 1168.2698313554029, 1189.9167058305923, 1196.5572738086876, 15515.069095854173, 15542.766858497132, 15673.92163039189, 15685.999999999885, 15705.607359816097, 3715.3126671565847, 3779.848806047727, 4431.933520997188, 4529.320601909674, 4606.032733126014, 11541.826872596917, 11550.018248448809, 11574.47914843834, 11584.884151158549, 11594.47824847884, 5621.409751976065, 5622.7750889963945, 5633.239512665819, 5637.23897273871, 5677.063284558001, 4990.326305948723, 5038.924416133761, 5111.642185047085, 5200.5059519345605, 5260.28986086881, 3741.2704522618374, 3798.5945200276074, 3804.6766024887643, 3856.9614303857165, 3874.3413619684156, 893.2362829780574, 926.8656044873455, 961.485605201214, 969.7333233360741, 970.3595626886363, 925.2551695884878, 931.2270815223484, 939.7556635274877, 952.7808604021114, 991.2714155095998, 6067.332593414721, 6260.280067792171, 6537.0, 6562.901556476657, 6634.900476492857, 957.7366224288377, 960.841461158901, 984.5667906121283, 1001.7545701303227, 1017.3285631483186, 4173.6661067114555, 4241.512226094039, 4242.278812601865, 4338.848140315074, 4357.869263922087, 12818.871811281955, 13518.391672374766, 13521.256330901895, 13540.0, 13545.796813047813, 10934.125269978382, 11266.94366528162, 13454.0, 13479.123856949464, 13537.39081741324, 9647.324687271965, 10045.949770251102, 10072.59709611622, 10088.74778130538, 10097.99020097999, 6778.423833974168, 6888.104546408935, 7031.437485001085, 7039.753608623673, 7071.0, 11915.76168476643, 12189.573364932185, 12285.57000504978, 12289.1397602167, 12339.0, 12120.151589388659, 12165.479315085615, 12314.13801033955, 12414.255144691348, 12448.190867593577, 8219.876701849596, 8281.130481299377, 8358.0, 8393.034800997963, 8401.873971890303, 9990.050945160197, 10081.143102836048, 10185.592576296865, 10196.388216706724, 10220.335678180894, 8641.697540586048, 8713.695020674246, 8716.302695784449, 8720.171583699539, 8772.605232764668, 6980.930190698028, 8461.450005749606, 8543.871841922251, 8552.529610871461, 8584.570771461276, 7732.690692372242, 7773.727919522789, 7868.527888326718, 7869.921300786918, 7876.330511906552, 5913.438223368773, 5939.0, 6051.7579296827635, 6063.333033366238, 6094.725737341919, 5949.375315591974, 6009.549283803644, 6062.333143354315, 6134.7852825152695, 6144.907826382519], "value": 5037.544888270921, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.44704008627366, 382.0544153220777, 382.5294887288635, 389.9044734040159, 392.29582898696844, 3851.6918646508284, 3895.1430685249234, 4519.322101684745, 4734.100520901026, 4988.725620090898, 3833.884983450494, 4572.519885412034, 4880.4875488073685, 4881.267809828528, 4925.113479573675, 4123.546409894909, 4165.812538435763, 4193.727407718497, 4206.516250631186, 4263.3178691409385, 3979.1245925896255, 4145.253854306224, 4182.79086045698, 4227.112306415656, 4335.501417336999, 3434.3818112739696, 4065.4714887064642, 4169.812358443877, 4228.915421691564, 4232.6613870890405, 3937.645611894929, 4133.958660413417, 4165.396017577465, 4177.182925427449, 4303.935440968388, 3845.731018698981, 3846.499955005859, 3874.380099184117, 3876.186000939806, 3908.964124507008, 4159.254891771417, 4306.44016277885, 4682.184020023704, 4944.505549445063, 4953.0683033616915, 3848.076461649212, 3958.940615890764, 4256.531781504032, 4394.143142087294, 4828.879278018043, 4703.600193983515, 5172.508611681884, 5299.894002119936, 5323.391538146319, 5375.946240537583, 3802.923941521154, 4119.949412899721, 4177.370825378105, 4310.676699247538, 4321.395004699345, 4305.203537345601, 4662.3705799716945, 5036.697798132087, 5445.700486473203, 5458.781648734085, 3955.4462375267485, 4085.7548547086676, 4351.4995775485595, 4371.977780442001, 4374.300111982067, 3677.1941041022415, 4048.149888523384, 4066.5933406659096, 4078.3286947049137, 4541.9545804542195, 3975.5229372475487, 4460.799264033127, 4804.543568361, 4851.684640498427, 4940.431850337185, 4782.641301902371, 4813.0026039194045, 5103.923441148311, 5270.025045366619, 5299.682019078868, 1380.6814492159963, 1464.2678660669665, 1465.7654775236124, 1482.2657490735864, 1508.2052357694308, 1086.4893500054961, 1139.8974092331564, 1142.828575713633, 1146.5643055638805, 1160.7736491384098, 4442.97811503356, 4670.813167473331, 5007.39911210657, 5092.9373603690565, 5157.742112894341, 5066.493350664904, 5568.575326174455, 5611.2144299798065, 5615.630621627073, 5678.0, 4738.881527961759, 4844.15227335215, 4965.528274813887, 4997.775100120503, 5055.317532133115, 2597.584386498189, 2632.9736702633113, 2647.8543680097778, 2881.671669222052, 2897.3191300044473, 4944.640223938446, 5120.948790512047, 5427.887283107033, 5777.3933736957015, 5866.6799970006405, 4426.712263702913, 4846.975765121152, 5081.644284900095, 5555.972220138954, 5714.485696287354, 5591.35055158721, 5606.299212598426, 5611.719414029282, 5662.377138514842, 5669.659820410789, 4870.512948705101, 5410.702411367412, 5441.863953401196, 5487.835364939058, 5521.365043019943, 4526.705764125321, 5170.37955445349, 5180.7566184114785, 5247.89504209906, 5496.251874062968, 5315.601329900351, 5760.769569217185, 5765.682887441231, 5771.480566749013, 5824.650520968756, 3787.6212378763007, 3798.993266784249, 4095.89760255996, 4412.602865742098, 4419.359192916945, 4756.476787553435, 5286.809938483582, 5497.422770609029, 5523.7790488380015, 5582.246396736551, 4757.928631070606, 5317.494837990384, 5343.3587969443915, 5349.572034237174, 5360.0, 4742.905141897212, 5190.8961820764125, 5352.759125839269, 5388.595855310789, 5468.0, 4657.976710116428, 4737.478877323423, 4895.730734809477, 5189.822193134563, 5218.26944227816, 4570.9943812361225, 5126.948730512647, 5336.599755018312, 5356.473405079533, 5377.983256278895, 4077.653399461079, 5209.661372010807, 5213.40045894727, 5214.530692237717, 5227.503387178212], "value": 4062.6113728089413, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.673256935525, 706.9646517674122, 741.0588552538273, 765.9961700191499, 859.4284800607596, 14882.0, 14959.177245251507, 15267.236638168102, 15350.846491535081, 15616.297266623014, 12837.22976621401, 12997.935010324934, 14383.0, 14522.782158267608, 14540.854591454083, 934.2339281788936, 940.5673390240463, 944.2398868910524, 946.011418068117, 946.597695979206, 926.7173512078813, 926.7822061815468, 958.094600602431, 964.2045312617084, 974.2206235011962, 998.331118150837, 1003.2976916158682, 1005.4369553050278, 1007.2848277722788, 1009.3590569988073, 991.4992928571097, 996.9651062212861, 999.6451259802737, 1001.159026417809, 1001.329109496639, 743.2901578992049, 743.5167141358106, 743.7619961612286, 743.8363560016787, 747.3460721868365, 15316.693666126726, 15638.609034774108, 15708.607284817857, 15801.288941997638, 16072.357105715757, 13866.93066534671, 14033.228172450512, 14039.438422463089, 14177.220252886087, 14192.290385480788, 11584.884151158465, 11705.12211584126, 11978.460969256404, 12129.272243665408, 12443.377831108408, 3552.5674560156162, 3617.113807117247, 3621.058524783549, 3627.836747346376, 3676.283124790667, 55578.33373815886, 82527.0, 83564.0, 107465.46267268692, 111309.44345278303, 10390.43539974082, 10626.203034772423, 10842.295250808673, 10910.945445272882, 11138.0, 21534.892325538272, 22199.1120355184, 22204.888975555343, 22213.888930555244, 22248.332550023526, 7009.9649501752165, 7829.138794732575, 7911.525308481395, 7932.563708996061, 7955.164707705723, 4224.577542245751, 4225.429567008476, 4303.2469317869245, 4356.564343565618, 4389.846355377546, 3408.50576666382, 3506.1760486285702, 3510.9648903511147, 3512.016635342108, 3524.665156810099, 621.3351713666334, 637.1398611873933, 646.1890327638783, 661.2593894837812, 661.7617657643244, 659.5712786688691, 685.6400389795298, 687.1856849633101, 711.8967749676268, 723.5767076260447, 9597.712068637813, 9991.03049631245, 10130.645427410005, 10131.392116473035, 10196.694099177037, 609.9878002439926, 637.6843462486099, 672.9636360005635, 686.7905288886938, 689.334791925784, 4694.225452800318, 4903.926441103386, 4904.092742842586, 4916.508349165055, 4918.680285781413, 104879.0, 105670.47164764127, 107221.4638926816, 108036.45981770198, 111193.0, 73530.26469735234, 79926.20073799304, 88399.55800221086, 88599.11400886037, 89144.0, 62309.0, 69470.3052969474, 69539.60920781556, 75540.62229688927, 77085.22914770893, 9036.081575736394, 11003.0, 11265.14125169338, 11359.54561817518, 11372.0, 48051.5194848061, 49156.50843491662, 49160.26259606037, 49824.50175498199, 51430.48569514257, 63555.0, 65838.34161658514, 67101.993470097, 67151.0, 67179.32820671731, 12279.815802763145, 12374.566890158801, 12380.504779808707, 12494.937525312314, 12648.810267846175, 13478.730425391634, 14071.507497237548, 14647.12117272967, 15219.239038047832, 15232.086074835544, 11654.883451165719, 13074.932566175, 13710.38303276375, 13814.65463363424, 13859.606194226182, 12610.810837837256, 13600.251986140856, 13601.523946661819, 13607.0, 13673.589792306248, 12170.148089633994, 13355.198688078746, 13484.258365789976, 13502.797458037941, 13518.459261629818, 8916.73249802507, 9313.720588382359, 9498.525073746147, 9616.90383096188, 9640.843098828185, 10014.353045179763, 10750.59808612459, 11383.146264030065, 11395.544178232778, 11435.885641143483], "value": 7523.285471148315, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1463.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 115.0, 115.0, 115.0, 116.0, 115.0, 115.0, 114.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 107.0, 113.0, 147.0, 111.0, 111.0, 111.0, 110.0, 131.0, 111.0], "value": 119.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 188.0, 182.0, 183.0, 187.0, 185.0, 183.0, 185.0, 186.0, 186.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 62.0, 60.0, 93.0, 62.0, 62.0, 61.0, 61.0, 77.0, 62.0], "value": 68.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 86.0, 73.0, 69.0, 68.0, 81.0, 67.0, 68.0, 82.0], "value": 74.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 136.0, 88.0, 88.0, 87.0, 88.0, 88.0, 87.0, 88.0, 86.0], "value": 95.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 102.0, 95.0, 95.0, 105.0, 153.0, 99.0], "value": 103.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 86.0, 83.0, 87.0, 84.0, 84.0, 87.0, 85.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 58.0, 60.0, 60.0, 63.0, 64.0, 64.0, 62.0, 63.0, 65.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 60.0, 65.0, 66.0, 61.0, 60.0, 80.0, 84.0, 85.0, 85.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 86.0, 87.0, 84.0, 85.0, 82.0, 90.0, 89.0, 114.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 136.0, 137.0, 132.0, 137.0, 137.0, 140.0, 154.0, 138.0], "value": 138.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 169.0, 149.0, 231.0, 227.0, 214.0, 163.0, 212.0, 229.0], "value": 192.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 67.0, 64.0, 64.0, 64.0, 65.0], "value": 65.0, "unit": "score"}]}]} 09:31:02 INFO - Return code: 0 09:31:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:31:02 ERROR - # TBPL SUCCESS # 09:31:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:31:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:31:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:31:02 INFO - 2017-04-14 09:31:02.590 screenresolution[1928:17854] starting screenresolution argv=screenresolution get 09:31:02 INFO - 2017-04-14 09:31:02.593 screenresolution[1928:17854] Display 0: 1600x1200x32@60 09:31:02 INFO - 2017-04-14 09:31:02.599 screenresolution[1929:17857] starting screenresolution argv=screenresolution list 09:31:02 INFO - Available Modes on Display 0 09:31:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:31:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:31:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:31:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:31:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:31:02 INFO - Intel Iris: 09:31:02 INFO - Chipset Model: Intel Iris 09:31:02 INFO - Type: GPU 09:31:02 INFO - Bus: Built-In 09:31:02 INFO - VRAM (Dynamic, Max): 1536 MB 09:31:02 INFO - Vendor: Intel (0x8086) 09:31:02 INFO - Device ID: 0x0a2e 09:31:02 INFO - Revision ID: 0x0009 09:31:02 INFO - Displays: 09:31:02 INFO - Display: 09:31:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:31:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:31:02 INFO - Display Serial Number: 1600x1200 60 09:31:02 INFO - Main Display: Yes 09:31:02 INFO - Mirror: Off 09:31:02 INFO - Online: Yes 09:31:02 INFO - Rotation: Supported 09:31:02 INFO - Return code: 0 09:31:02 INFO - Running post-action listener: _resource_record_post_action 09:31:02 INFO - [mozharness: 2017-04-14 16:31:02.837960Z] Finished run-tests step (success) 09:31:02 INFO - Running post-run listener: _resource_record_post_run 09:31:02 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 811008; Write bytes: 510948864; Read time: 12; Write time: 1724 09:31:02 INFO - TinderboxPrint: CPU usage
53.7% 09:31:02 INFO - TinderboxPrint: I/O read bytes / time
811,008 / 12 09:31:02 INFO - TinderboxPrint: I/O write bytes / time
510,948,864 / 1,724 09:31:02 INFO - TinderboxPrint: CPU idle
2,226.7 (46.3%) 09:31:02 INFO - TinderboxPrint: CPU system
958.1 (19.9%) 09:31:02 INFO - TinderboxPrint: CPU user
1,620.9 (33.7%) 09:31:02 INFO - TinderboxPrint: Swap in / out
209,199,104 / 0 09:31:02 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205748736; Write bytes: 199323648; Read time: 15727; Write time: 375 09:31:02 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 770048; Write bytes: 296167424; Read time: 12; Write time: 1191 09:31:03 INFO - Running post-run listener: _upload_blobber_files 09:31:03 INFO - Blob upload gear active. 09:31:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:31:03 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 09:31:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:31:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:31:03 INFO - (blobuploader) - INFO - Open directory for files ... 09:31:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:31:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:31:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:04 INFO - (blobuploader) - INFO - Done attempting. 09:31:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:31:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:31:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:05 INFO - (blobuploader) - INFO - Done attempting. 09:31:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:31:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:31:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:06 INFO - (blobuploader) - INFO - Done attempting. 09:31:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:31:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:31:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:08 INFO - (blobuploader) - INFO - Done attempting. 09:31:08 INFO - (blobuploader) - INFO - Iteration through files over. 09:31:08 INFO - Return code: 0 09:31:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:31:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:31:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/37f569f90f6aeab87ecf0433343afa743608ec719c1a202d7cccace2c74f83a7033c3ebf8f83df7c394a6fb6eb80e8a9c4187ed9ad879052d8f195fccbd172d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9673edf5be1379f13eb4b9ac4a2adeffff1871a43f3008598d904c578967ae1e151eebc1b2d1148bcfe5282c07299f98aefe431cc851ba07531a41c911a9777b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e36719b782729ac9d7863dda934b875cec118efa3e6e4e5e4e19e1c9e818f20aba51853fc62bcb1dfcafb6b88d154bf304d9d337c52de53ee39517bf7466c792", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e12a1a7a7f33922f60fc3c4edbd9ae166ac241531a38d604511772fdbddbb5e444589dc20043394d35cdeff2d5f5426e93e7209e2f7034451cf15ef4a6ef8344"} 09:31:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:31:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:31:08 INFO - Contents: 09:31:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/37f569f90f6aeab87ecf0433343afa743608ec719c1a202d7cccace2c74f83a7033c3ebf8f83df7c394a6fb6eb80e8a9c4187ed9ad879052d8f195fccbd172d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9673edf5be1379f13eb4b9ac4a2adeffff1871a43f3008598d904c578967ae1e151eebc1b2d1148bcfe5282c07299f98aefe431cc851ba07531a41c911a9777b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e36719b782729ac9d7863dda934b875cec118efa3e6e4e5e4e19e1c9e818f20aba51853fc62bcb1dfcafb6b88d154bf304d9d337c52de53ee39517bf7466c792", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e12a1a7a7f33922f60fc3c4edbd9ae166ac241531a38d604511772fdbddbb5e444589dc20043394d35cdeff2d5f5426e93e7209e2f7034451cf15ef4a6ef8344"} 09:31:08 INFO - Running post-run listener: copy_logs_to_upload_dir 09:31:08 INFO - Copying logs to upload dir... 09:31:08 INFO - mkdir: /builds/slave/test/build/upload/logs 09:31:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.678295 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-14 09:31:08.772913) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-14 09:31:08.778773) ========= 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.fAUVBGl2EB/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.auz1Y19nAg/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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/Mozilla-Aurora/sha512/37f569f90f6aeab87ecf0433343afa743608ec719c1a202d7cccace2c74f83a7033c3ebf8f83df7c394a6fb6eb80e8a9c4187ed9ad879052d8f195fccbd172d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9673edf5be1379f13eb4b9ac4a2adeffff1871a43f3008598d904c578967ae1e151eebc1b2d1148bcfe5282c07299f98aefe431cc851ba07531a41c911a9777b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e36719b782729ac9d7863dda934b875cec118efa3e6e4e5e4e19e1c9e818f20aba51853fc62bcb1dfcafb6b88d154bf304d9d337c52de53ee39517bf7466c792", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e12a1a7a7f33922f60fc3c4edbd9ae166ac241531a38d604511772fdbddbb5e444589dc20043394d35cdeff2d5f5426e93e7209e2f7034451cf15ef4a6ef8344"} build_url:https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011126 build_url: 'https://queue.taskcluster.net/v1/task/Pe7iKVzdTCW5BVgYaNxXkw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/37f569f90f6aeab87ecf0433343afa743608ec719c1a202d7cccace2c74f83a7033c3ebf8f83df7c394a6fb6eb80e8a9c4187ed9ad879052d8f195fccbd172d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9673edf5be1379f13eb4b9ac4a2adeffff1871a43f3008598d904c578967ae1e151eebc1b2d1148bcfe5282c07299f98aefe431cc851ba07531a41c911a9777b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e36719b782729ac9d7863dda934b875cec118efa3e6e4e5e4e19e1c9e818f20aba51853fc62bcb1dfcafb6b88d154bf304d9d337c52de53ee39517bf7466c792", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e12a1a7a7f33922f60fc3c4edbd9ae166ac241531a38d604511772fdbddbb5e444589dc20043394d35cdeff2d5f5426e93e7209e2f7034451cf15ef4a6ef8344"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-14 09:31:08.813589) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 09:31:08.813905) ========= 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.fAUVBGl2EB/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.auz1Y19nAg/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.005134 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 09:31:08.862258) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-14 09:31:08.862577) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-14 09:31:08.862960) ========= ========= Total master_lag: 0.18 =========