builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0072 starttime: 1494232168.14 results: success (0) buildid: 20170508004016 builduid: c0b78e94bfa6442c9266ff2c1911b288 revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-08 01:29:28.138202) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-08 01:29:28.138693) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-08 01:29:28.138982) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-08 01:29:28.170184) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 01:29:28.170555) ========= 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.LocHGaiKhf/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.xX9IpPzt2V/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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-05-08 01:29:28-- 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% 155M=0s 2017-05-08 01:29:28 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.601184 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 01:29:28.797241) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 01:29:28.797581) ========= 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.LocHGaiKhf/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.xX9IpPzt2V/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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.029123 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 01:29:28.848955) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-08 01:29:28.849333) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --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 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LocHGaiKhf/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.xX9IpPzt2V/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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-05-08 01:29:28,949 truncating revision to first 12 chars 2017-05-08 01:29:28,949 Setting DEBUG logging. 2017-05-08 01:29:28,949 attempt 1/10 2017-05-08 01:29:28,949 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-08 01:29:29,934 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.255530 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-08 01:29:30.129395) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-08 01:29:30.129791) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-08 01:29:30.130280) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-08 01:29:30.130561) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-08 01:29:30.160823) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-08 01:29:30.161249) ========= /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.LocHGaiKhf/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.xX9IpPzt2V/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 01:29:30 INFO - MultiFileLogger online at 20170508 01:29:30 in /builds/slave/test 01:29:30 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 01:29:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:29:30 INFO - {'append_to_log': False, 01:29:30 INFO - 'base_work_dir': '/builds/slave/test', 01:29:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:29:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:29:30 INFO - 'branch': 'Mozilla-Aurora', 01:29:30 INFO - 'buildbot_json_path': 'buildprops.json', 01:29:30 INFO - 'config_files': ('talos/mac_config.py',), 01:29:30 INFO - 'default_actions': ('clobber', 01:29:30 INFO - 'read-buildbot-config', 01:29:30 INFO - 'download-and-extract', 01:29:30 INFO - 'populate-webroot', 01:29:30 INFO - 'create-virtualenv', 01:29:30 INFO - 'install', 01:29:30 INFO - 'run-tests'), 01:29:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:29:30 INFO - 'download_minidump_stackwalk': True, 01:29:30 INFO - 'download_symbols': 'ondemand', 01:29:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:29:30 INFO - 'tooltool.py': '/tools/tooltool.py', 01:29:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:29:30 INFO - '/tools/misc-python/virtualenv.py')}, 01:29:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:29:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:29:30 INFO - 'gecko_profile': False, 01:29:30 INFO - 'gecko_profile_interval': 0, 01:29:30 INFO - 'installer_path': 'installer.exe', 01:29:30 INFO - 'log_level': 'info', 01:29:30 INFO - 'log_name': 'talos', 01:29:30 INFO - 'log_to_console': True, 01:29:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:29:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:29:30 INFO - 'opt_config_files': (), 01:29:30 INFO - 'pip_index': False, 01:29:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:29:30 INFO - 'cmd': ('bash', 01:29:30 INFO - '-c', 01:29:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:29:30 INFO - 'enabled': True, 01:29:30 INFO - 'halt_on_failure': False, 01:29:30 INFO - 'name': 'check_screen_resolution'},), 01:29:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:29:30 INFO - 'cmd': ('bash', 01:29:30 INFO - '-c', 01:29:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:29:30 INFO - 'enabled': True, 01:29:30 INFO - 'halt_on_failure': False, 01:29:30 INFO - 'name': 'check_screen_resolution'},), 01:29:30 INFO - 'run_cmd_checks_enabled': True, 01:29:30 INFO - 'suite': 'dromaeojs', 01:29:30 INFO - 'system_bits': '32', 01:29:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:29:30 INFO - 'title': 't-yosemite-r7-0072', 01:29:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:29:30 INFO - 'use_talos_json': True, 01:29:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:29:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:29:30 INFO - 'work_dir': 'build'} 01:29:30 INFO - [mozharness: 2017-05-08 08:29:30.372804Z] Running clobber step. 01:29:30 INFO - Running pre-action listener: _resource_record_pre_action 01:29:30 INFO - Running main action method: clobber 01:29:30 INFO - rmtree: /builds/slave/test/build 01:29:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:29:32 INFO - Running post-action listener: _resource_record_post_action 01:29:32 INFO - [mozharness: 2017-05-08 08:29:32.976920Z] Finished clobber step (success) 01:29:32 INFO - [mozharness: 2017-05-08 08:29:32.977044Z] Running read-buildbot-config step. 01:29:32 INFO - Running pre-action listener: _resource_record_pre_action 01:29:32 INFO - Running main action method: read_buildbot_config 01:29:32 INFO - Using buildbot properties: 01:29:32 INFO - { 01:29:32 INFO - "project": "", 01:29:32 INFO - "product": "firefox", 01:29:32 INFO - "script_repo_revision": "production", 01:29:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 01:29:32 INFO - "repository": "", 01:29:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 01:29:32 INFO - "buildid": "20170508004016", 01:29:32 INFO - "stage_platform": "macosx64", 01:29:32 INFO - "basedir": "/builds/slave/test", 01:29:32 INFO - "nightly_build": "True", 01:29:32 INFO - "buildnumber": 1, 01:29:32 INFO - "slavename": "t-yosemite-r7-0072", 01:29:32 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 01:29:32 INFO - "platform": "yosemite_r7", 01:29:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 01:29:32 INFO - "branch": "mozilla-aurora", 01:29:32 INFO - "repo_path": "releases/mozilla-aurora", 01:29:32 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 01:29:32 INFO - "pgo_build": "False", 01:29:32 INFO - "builduid": "c0b78e94bfa6442c9266ff2c1911b288", 01:29:32 INFO - "slavebuilddir": "test" 01:29:32 INFO - } 01:29:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 01:29:32 INFO - Running post-action listener: _resource_record_post_action 01:29:32 INFO - [mozharness: 2017-05-08 08:29:32.980338Z] Finished read-buildbot-config step (success) 01:29:32 INFO - [mozharness: 2017-05-08 08:29:32.980457Z] Running download-and-extract step. 01:29:32 INFO - Running pre-action listener: _resource_record_pre_action 01:29:32 INFO - Running main action method: download_and_extract 01:29:32 INFO - mkdir: /builds/slave/test/build/tests 01:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:29:32 INFO - trying https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 01:29:32 INFO - Downloading https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:29:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 01:29:33 INFO - Downloaded 1199 bytes. 01:29:33 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:29:33 INFO - Using the following test package requirements: 01:29:33 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:29:33 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:29:33 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 01:29:33 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:29:33 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 01:29:33 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:29:33 INFO - u'jsshell-mac.zip'], 01:29:33 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:29:33 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 01:29:33 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:29:33 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:29:33 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 01:29:33 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:29:33 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 01:29:33 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:29:33 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 01:29:33 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:29:33 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 01:29:33 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 01:29:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:29:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:29:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:29:35 INFO - Expected file size: 51791009 01:29:35 INFO - Obtained file size: 51791009 01:29:51 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 01:29:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:29:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:29:51 INFO - Fetch https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:29:53 INFO - Expected file size: 51791009 01:29:53 INFO - Obtained file size: 51791009 01:30:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 01:30:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 01:30:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 01:30:10 INFO - Expected file size: 14404424 01:30:10 INFO - Obtained file size: 14404424 01:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:10 INFO - trying https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:30:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 01:30:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 01:30:12 INFO - Downloaded 74779573 bytes. 01:30:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:30:12 INFO - mkdir: /builds/slave/test/properties 01:30:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:30:12 INFO - Writing to file /builds/slave/test/properties/build_url 01:30:12 INFO - Contents: 01:30:12 INFO - build_url:https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:30:13 INFO - Running post-action listener: _resource_record_post_action 01:30:13 INFO - Running post-action listener: set_extra_try_arguments 01:30:13 INFO - [mozharness: 2017-05-08 08:30:13.091490Z] Finished download-and-extract step (success) 01:30:13 INFO - [mozharness: 2017-05-08 08:30:13.091637Z] Running populate-webroot step. 01:30:13 INFO - Running pre-action listener: _resource_record_pre_action 01:30:13 INFO - Running main action method: populate_webroot 01:30:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:30:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:30:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:30:13 INFO - '--tppagecycles', 01:30:13 INFO - '7'], 01:30:13 INFO - 'tests': ['tsvgm']}}, 01:30:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:30:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:30:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:30:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:30:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:30:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:30:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:30:13 INFO - 'talos_options': ['--disable-e10s'], 01:30:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:30:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:30:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:30:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:30:13 INFO - 'talos_options': ['--disable-e10s'], 01:30:13 INFO - 'tests': ['damp', 'tps']}, 01:30:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:30:13 INFO - 'tests': ['damp', 'tps']}, 01:30:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:30:13 INFO - 'tests': ['dromaeo_dom']}, 01:30:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:30:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:30:13 INFO - 'tests': ['basic_compositor_video']}, 01:30:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:30:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:30:13 INFO - 'tests': ['a11yr', 01:30:13 INFO - 'ts_paint', 01:30:13 INFO - 'tpaint', 01:30:13 INFO - 'sessionrestore', 01:30:13 INFO - 'sessionrestore_no_auto_restore', 01:30:13 INFO - 'tabpaint']}, 01:30:13 INFO - 'other-e10s': {'tests': ['a11yr', 01:30:13 INFO - 'ts_paint', 01:30:13 INFO - 'tpaint', 01:30:13 INFO - 'sessionrestore', 01:30:13 INFO - 'sessionrestore_no_auto_restore', 01:30:13 INFO - 'tabpaint']}, 01:30:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:30:13 INFO - 'ts_paint', 01:30:13 INFO - 'tpaint', 01:30:13 INFO - 'sessionrestore', 01:30:13 INFO - 'sessionrestore_no_auto_restore', 01:30:13 INFO - 'tabpaint']}, 01:30:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:30:13 INFO - 'ts_paint', 01:30:13 INFO - 'tpaint', 01:30:13 INFO - 'sessionrestore', 01:30:13 INFO - 'sessionrestore_no_auto_restore', 01:30:13 INFO - 'tabpaint']}, 01:30:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:30:13 INFO - 'tests': ['a11yr', 01:30:13 INFO - 'ts_paint', 01:30:13 INFO - 'tpaint', 01:30:13 INFO - 'sessionrestore', 01:30:13 INFO - 'sessionrestore_no_auto_restore', 01:30:13 INFO - 'tabpaint']}, 01:30:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:30:13 INFO - 'tests': ['a11yr', 01:30:13 INFO - 'ts_paint', 01:30:13 INFO - 'tpaint', 01:30:13 INFO - 'sessionrestore', 01:30:13 INFO - 'sessionrestore_no_auto_restore', 01:30:13 INFO - 'tabpaint']}, 01:30:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:30:13 INFO - 'tests': ['tsvgx', 01:30:13 INFO - 'tsvgr_opacity', 01:30:13 INFO - 'tart', 01:30:13 INFO - 'tscrollx', 01:30:13 INFO - 'cart', 01:30:13 INFO - 'tsvg_static']}, 01:30:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:30:13 INFO - 'tsvgr_opacity', 01:30:13 INFO - 'tart', 01:30:13 INFO - 'tscrollx', 01:30:13 INFO - 'cart', 01:30:13 INFO - 'tsvg_static']}, 01:30:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:30:13 INFO - 'talos_options': ['--disable-e10s'], 01:30:13 INFO - 'tests': ['tp5o']}, 01:30:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:30:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:30:13 INFO - 'talos_options': ['--disable-e10s', 01:30:13 INFO - '--xperf_path', 01:30:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:30:13 INFO - 'tests': ['tp5n']}, 01:30:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:30:13 INFO - 'talos_options': ['--xperf_path', 01:30:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:30:13 INFO - 'tests': ['tp5n']}}, 01:30:13 INFO - 'talos.zip': {'path': '', 01:30:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:30:13 INFO - Running post-action listener: _resource_record_post_action 01:30:13 INFO - [mozharness: 2017-05-08 08:30:13.110620Z] Finished populate-webroot step (success) 01:30:13 INFO - [mozharness: 2017-05-08 08:30:13.110748Z] Running create-virtualenv step. 01:30:13 INFO - Running pre-action listener: _resource_record_pre_action 01:30:13 INFO - Running main action method: create_virtualenv 01:30:13 INFO - Creating virtualenv /builds/slave/test/build/venv 01:30:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:30:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:30:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:30:13 INFO - Using real prefix '/tools/python27' 01:30:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:30:13 INFO - Installing distribute.............................................................................................................................................................................................done. 01:30:16 INFO - Installing pip.................done. 01:30:16 INFO - Return code: 0 01:30:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:30:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:30:17 INFO - Reading from file tmpfile_stdout 01:30:17 INFO - Output received: 01:30:17 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 01:30:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:17 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 0x10cba0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc890d9b300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97ed30>, '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 0x10c8a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c973be8>, '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.LocHGaiKhf/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.xX9IpPzt2V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:30:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:30:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:30:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LocHGaiKhf/Render', 01:30:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:17 INFO - 'HOME': '/Users/cltbld', 01:30:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:17 INFO - 'LOGNAME': 'cltbld', 01:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:17 INFO - 'MOZ_NO_REMOTE': '1', 01:30:17 INFO - 'NO_EM_RESTART': '1', 01:30:17 INFO - 'PAGER': '/bin/cat', 01:30:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:17 INFO - 'PWD': '/builds/slave/test', 01:30:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:17 INFO - 'SHELL': '/bin/bash', 01:30:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xX9IpPzt2V/Listeners', 01:30:17 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 01:30:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:17 INFO - 'USER': 'cltbld', 01:30:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:17 INFO - 'XPC_FLAGS': '0x0', 01:30:17 INFO - 'XPC_SERVICE_NAME': '0', 01:30:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:30:17 INFO - Cleaning up... 01:30:17 INFO - Return code: 0 01:30:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:17 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 0x10cba0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc890d9b300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97ed30>, '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 0x10c8a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c973be8>, '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.LocHGaiKhf/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.xX9IpPzt2V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:30:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:30:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:30:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LocHGaiKhf/Render', 01:30:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:17 INFO - 'HOME': '/Users/cltbld', 01:30:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:17 INFO - 'LOGNAME': 'cltbld', 01:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:17 INFO - 'MOZ_NO_REMOTE': '1', 01:30:17 INFO - 'NO_EM_RESTART': '1', 01:30:17 INFO - 'PAGER': '/bin/cat', 01:30:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:17 INFO - 'PWD': '/builds/slave/test', 01:30:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:17 INFO - 'SHELL': '/bin/bash', 01:30:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xX9IpPzt2V/Listeners', 01:30:17 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 01:30:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:17 INFO - 'USER': 'cltbld', 01:30:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:17 INFO - 'XPC_FLAGS': '0x0', 01:30:17 INFO - 'XPC_SERVICE_NAME': '0', 01:30:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:17 INFO - Downloading/unpacking psutil>=3.1.1 01:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:30:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:30:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:30:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:30:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:30:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:30:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:30:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:30:20 INFO - Installing collected packages: psutil 01:30:20 INFO - Running setup.py install for psutil 01:30:20 INFO - building 'psutil._psutil_osx' extension 01:30:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 01:30:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 01:30:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 01:30:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 01:30:21 INFO - building 'psutil._psutil_posix' extension 01:30:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 01:30:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:30:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:30:21 INFO - ^ 01:30:21 INFO - 1 warning generated. 01:30:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 01:30:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:30:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:30:21 INFO - Successfully installed psutil 01:30:21 INFO - Cleaning up... 01:30:21 INFO - Return code: 0 01:30:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:30: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')]} 01:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30: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')]} 01:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cba0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc890d9b300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97ed30>, '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 0x10c8a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c973be8>, '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.LocHGaiKhf/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.xX9IpPzt2V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:30:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:30:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LocHGaiKhf/Render', 01:30:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:21 INFO - 'HOME': '/Users/cltbld', 01:30:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:21 INFO - 'LOGNAME': 'cltbld', 01:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:21 INFO - 'MOZ_NO_REMOTE': '1', 01:30:21 INFO - 'NO_EM_RESTART': '1', 01:30:21 INFO - 'PAGER': '/bin/cat', 01:30:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:21 INFO - 'PWD': '/builds/slave/test', 01:30:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:21 INFO - 'SHELL': '/bin/bash', 01:30:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xX9IpPzt2V/Listeners', 01:30:21 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 01:30:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:21 INFO - 'USER': 'cltbld', 01:30:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:21 INFO - 'XPC_FLAGS': '0x0', 01:30:21 INFO - 'XPC_SERVICE_NAME': '0', 01:30:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:30:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:30:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:30:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:30:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:30:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:30:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:30:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:30:23 INFO - Installing collected packages: mozsystemmonitor 01:30:23 INFO - Running setup.py install for mozsystemmonitor 01:30:23 INFO - Successfully installed mozsystemmonitor 01:30:23 INFO - Cleaning up... 01:30:23 INFO - Return code: 0 01:30:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:30: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')]} 01:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30: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')]} 01:30:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:23 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 0x10cba0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc890d9b300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97ed30>, '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 0x10c8a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c973be8>, '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.LocHGaiKhf/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.xX9IpPzt2V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:30:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:30:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LocHGaiKhf/Render', 01:30:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:23 INFO - 'HOME': '/Users/cltbld', 01:30:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:23 INFO - 'LOGNAME': 'cltbld', 01:30:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:23 INFO - 'MOZ_NO_REMOTE': '1', 01:30:23 INFO - 'NO_EM_RESTART': '1', 01:30:23 INFO - 'PAGER': '/bin/cat', 01:30:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:23 INFO - 'PWD': '/builds/slave/test', 01:30:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:23 INFO - 'SHELL': '/bin/bash', 01:30:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xX9IpPzt2V/Listeners', 01:30:23 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 01:30:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:23 INFO - 'USER': 'cltbld', 01:30:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:23 INFO - 'XPC_FLAGS': '0x0', 01:30:23 INFO - 'XPC_SERVICE_NAME': '0', 01:30:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:23 INFO - Downloading/unpacking jsonschema==2.5.1 01:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:30:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:30:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:30:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:30:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:30:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:30:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:30:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:30:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:30:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:30:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:30:25 INFO - Downloading functools32-3.2.3-2.tar.gz 01:30:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:30:25 INFO - warning: no files found matching '*.txt' 01:30:25 INFO - no previously-included directories found matching 'build' 01:30:25 INFO - no previously-included directories found matching 'dist' 01:30:25 INFO - no previously-included directories found matching '.git*' 01:30:25 INFO - Installing collected packages: jsonschema, functools32 01:30:25 INFO - Running setup.py install for functools32 01:30:25 INFO - warning: no files found matching '*.txt' 01:30:25 INFO - no previously-included directories found matching 'build' 01:30:25 INFO - no previously-included directories found matching 'dist' 01:30:25 INFO - no previously-included directories found matching '.git*' 01:30:25 INFO - Successfully installed jsonschema functools32 01:30:25 INFO - Cleaning up... 01:30:25 INFO - Return code: 0 01:30:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:30:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:25 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 0x10cba0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc890d9b300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97ed30>, '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 0x10c8a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c973be8>, '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.LocHGaiKhf/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.xX9IpPzt2V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:30:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:30:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:30:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LocHGaiKhf/Render', 01:30:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:25 INFO - 'HOME': '/Users/cltbld', 01:30:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:25 INFO - 'LOGNAME': 'cltbld', 01:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:25 INFO - 'MOZ_NO_REMOTE': '1', 01:30:25 INFO - 'NO_EM_RESTART': '1', 01:30:25 INFO - 'PAGER': '/bin/cat', 01:30:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:25 INFO - 'PWD': '/builds/slave/test', 01:30:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:25 INFO - 'SHELL': '/bin/bash', 01:30:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xX9IpPzt2V/Listeners', 01:30:25 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 01:30:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:25 INFO - 'USER': 'cltbld', 01:30:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:25 INFO - 'XPC_FLAGS': '0x0', 01:30:25 INFO - 'XPC_SERVICE_NAME': '0', 01:30:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:30:25 INFO - Cleaning up... 01:30:25 INFO - Return code: 0 01:30:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:30:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:25 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 0x10cba0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc890d9b300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97ed30>, '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 0x10c8a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c973be8>, '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.LocHGaiKhf/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.xX9IpPzt2V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:30:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:30:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:30:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LocHGaiKhf/Render', 01:30:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:25 INFO - 'HOME': '/Users/cltbld', 01:30:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:25 INFO - 'LOGNAME': 'cltbld', 01:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:25 INFO - 'MOZ_NO_REMOTE': '1', 01:30:25 INFO - 'NO_EM_RESTART': '1', 01:30:25 INFO - 'PAGER': '/bin/cat', 01:30:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:25 INFO - 'PWD': '/builds/slave/test', 01:30:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:25 INFO - 'SHELL': '/bin/bash', 01:30:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xX9IpPzt2V/Listeners', 01:30:25 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 01:30:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:25 INFO - 'USER': 'cltbld', 01:30:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:25 INFO - 'XPC_FLAGS': '0x0', 01:30:25 INFO - 'XPC_SERVICE_NAME': '0', 01:30:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:26 INFO - Downloading/unpacking blobuploader==1.2.4 01:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:30:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:30:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:30:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:30:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:30:27 INFO - Downloading blobuploader-1.2.4.tar.gz 01:30:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:30:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:30:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:30:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:30:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:30:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:30:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:30:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:30:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:30:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:30:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:30:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:30:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:30:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:30:28 INFO - Downloading docopt-0.6.1.tar.gz 01:30:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:30:28 INFO - Installing collected packages: blobuploader, requests, docopt 01:30:28 INFO - Running setup.py install for blobuploader 01:30:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:30:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:30:28 INFO - Running setup.py install for requests 01:30:29 INFO - Running setup.py install for docopt 01:30:29 INFO - Successfully installed blobuploader requests docopt 01:30:29 INFO - Cleaning up... 01:30:29 INFO - Return code: 0 01:30:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:29 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 0x10cba0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc890d9b300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97ed30>, '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 0x10c8a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c973be8>, '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.LocHGaiKhf/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.xX9IpPzt2V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:30:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:30:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:30:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LocHGaiKhf/Render', 01:30:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:29 INFO - 'HOME': '/Users/cltbld', 01:30:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:29 INFO - 'LOGNAME': 'cltbld', 01:30:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:29 INFO - 'MOZ_NO_REMOTE': '1', 01:30:29 INFO - 'NO_EM_RESTART': '1', 01:30:29 INFO - 'PAGER': '/bin/cat', 01:30:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:29 INFO - 'PWD': '/builds/slave/test', 01:30:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:29 INFO - 'SHELL': '/bin/bash', 01:30:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xX9IpPzt2V/Listeners', 01:30:29 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 01:30:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:29 INFO - 'USER': 'cltbld', 01:30:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:29 INFO - 'XPC_FLAGS': '0x0', 01:30:29 INFO - 'XPC_SERVICE_NAME': '0', 01:30:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:30:29 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-JRj9KF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:30:29 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-Hiz3ZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:30:29 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-bpfRrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:30:29 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-tNsBls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:30:30 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-LUwAHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:30:30 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-cjK9f0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:30:30 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-B1L9fW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:30:30 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-6P9rvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:30:30 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-QOxSgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:30:30 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-5iZubE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:30:30 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-d5dILE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:30:30 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-tfXhYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:30:30 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-KIsFpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:30:30 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-32HplV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:30:31 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-32iLhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:30:31 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-XvgiRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:30:31 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-DeUhyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:30:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:30:31 INFO - Running setup.py install for manifestparser 01:30:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:30:31 INFO - Running setup.py install for mozcrash 01:30:31 INFO - Running setup.py install for mozdebug 01:30:31 INFO - Running setup.py install for mozdevice 01:30:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:30:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:30:31 INFO - Running setup.py install for mozfile 01:30:32 INFO - Running setup.py install for mozhttpd 01:30:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:30:32 INFO - Running setup.py install for mozinfo 01:30:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:30:32 INFO - Running setup.py install for mozInstall 01:30:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:30:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:30:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:30:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:30:32 INFO - Running setup.py install for mozleak 01:30:32 INFO - Running setup.py install for mozlog 01:30:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:30:32 INFO - Running setup.py install for moznetwork 01:30:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:30:32 INFO - Running setup.py install for mozprocess 01:30:33 INFO - Running setup.py install for mozprofile 01:30:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:30:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:30:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:30:33 INFO - Running setup.py install for mozrunner 01:30:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:30:33 INFO - Running setup.py install for mozscreenshot 01:30:33 INFO - Running setup.py install for moztest 01:30:33 INFO - Running setup.py install for mozversion 01:30:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:30:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:30:33 INFO - Cleaning up... 01:30:33 INFO - Return code: 0 01:30:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:30:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:33 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 0x10cba0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc890d9b300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97ed30>, '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 0x10c8a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c973be8>, '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.LocHGaiKhf/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.xX9IpPzt2V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:30:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:30:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:30:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LocHGaiKhf/Render', 01:30:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:33 INFO - 'HOME': '/Users/cltbld', 01:30:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:33 INFO - 'LOGNAME': 'cltbld', 01:30:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:33 INFO - 'MOZ_NO_REMOTE': '1', 01:30:33 INFO - 'NO_EM_RESTART': '1', 01:30:33 INFO - 'PAGER': '/bin/cat', 01:30:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:33 INFO - 'PWD': '/builds/slave/test', 01:30:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:33 INFO - 'SHELL': '/bin/bash', 01:30:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xX9IpPzt2V/Listeners', 01:30:33 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 01:30:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:33 INFO - 'USER': 'cltbld', 01:30:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:33 INFO - 'XPC_FLAGS': '0x0', 01:30:33 INFO - 'XPC_SERVICE_NAME': '0', 01:30:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:30:34 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-o0fkt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:30:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:30:34 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-fa6Ibl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:30:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:30:34 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-CFdeZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:30:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:30:34 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-P9B0Fd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:30:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:30:34 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-lcs4mC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:30:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:30:34 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-sprDVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:30:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:30:34 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-MUCdyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:30:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:30:34 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-hlYCnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:30:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:30:35 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-c5A_Zc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:30:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:30:35 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-MuVds9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:30:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:30:35 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-e7vkUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:30:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:30:35 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-lntifR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:30:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:30:35 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-xpHFpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:30:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:30:35 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-pnKsna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:30:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:30:35 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-Fh7bER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:30:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:30:35 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-zvnIGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:30:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:30:35 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-2KcFFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:30:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:30: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 01:30: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 01:30: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 01:30: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 01:30: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 01:30: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 01:30:37 INFO - Downloading blessings-1.6.tar.gz 01:30:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:30:37 INFO - Installing collected packages: blessings 01:30:37 INFO - Running setup.py install for blessings 01:30:37 INFO - Successfully installed blessings 01:30:37 INFO - Cleaning up... 01:30:37 INFO - Return code: 0 01:30:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:30:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:30:38 INFO - Reading from file tmpfile_stdout 01:30:38 INFO - Current package versions: 01:30:38 INFO - blessings == 1.6 01:30:38 INFO - blobuploader == 1.2.4 01:30:38 INFO - docopt == 0.6.1 01:30:38 INFO - functools32 == 3.2.3-2 01:30:38 INFO - jsonschema == 2.5.1 01:30:38 INFO - manifestparser == 1.1 01:30:38 INFO - mozInstall == 1.12 01:30:38 INFO - mozcrash == 1.0 01:30:38 INFO - mozdebug == 0.1 01:30:38 INFO - mozdevice == 0.49 01:30:38 INFO - mozfile == 1.2 01:30:38 INFO - mozhttpd == 0.7 01:30:38 INFO - mozinfo == 0.9 01:30:38 INFO - mozleak == 0.1 01:30:38 INFO - mozlog == 3.4 01:30:38 INFO - moznetwork == 0.27 01:30:38 INFO - mozprocess == 0.25 01:30:38 INFO - mozprofile == 0.28 01:30:38 INFO - mozrunner == 6.13 01:30:38 INFO - mozscreenshot == 0.1 01:30:38 INFO - mozsystemmonitor == 0.3 01:30:38 INFO - moztest == 0.8 01:30:38 INFO - mozversion == 1.4 01:30:38 INFO - psutil == 3.1.1 01:30:38 INFO - requests == 1.2.3 01:30:38 INFO - wsgiref == 0.1.2 01:30:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:38 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 0x10cba0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc890d9b300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97ed30>, '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 0x10c8a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c973be8>, '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.LocHGaiKhf/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.xX9IpPzt2V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:30:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 01:30:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:30:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LocHGaiKhf/Render', 01:30:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:38 INFO - 'HOME': '/Users/cltbld', 01:30:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:38 INFO - 'LOGNAME': 'cltbld', 01:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:38 INFO - 'MOZ_NO_REMOTE': '1', 01:30:38 INFO - 'NO_EM_RESTART': '1', 01:30:38 INFO - 'PAGER': '/bin/cat', 01:30:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:38 INFO - 'PWD': '/builds/slave/test', 01:30:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:38 INFO - 'SHELL': '/bin/bash', 01:30:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xX9IpPzt2V/Listeners', 01:30:38 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 01:30:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:38 INFO - 'USER': 'cltbld', 01:30:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:38 INFO - 'XPC_FLAGS': '0x0', 01:30:38 INFO - 'XPC_SERVICE_NAME': '0', 01:30:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 01:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:30:38 INFO - Cleaning up... 01:30:38 INFO - Return code: 0 01:30:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:38 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 0x10cba0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc890d9b300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97ed30>, '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 0x10c8a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c973be8>, '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.LocHGaiKhf/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.xX9IpPzt2V/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:30:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:30:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:30:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LocHGaiKhf/Render', 01:30:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:38 INFO - 'HOME': '/Users/cltbld', 01:30:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:38 INFO - 'LOGNAME': 'cltbld', 01:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:38 INFO - 'MOZ_NO_REMOTE': '1', 01:30:38 INFO - 'NO_EM_RESTART': '1', 01:30:38 INFO - 'PAGER': '/bin/cat', 01:30:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:38 INFO - 'PWD': '/builds/slave/test', 01:30:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:38 INFO - 'SHELL': '/bin/bash', 01:30:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xX9IpPzt2V/Listeners', 01:30:38 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 01:30:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:38 INFO - 'USER': 'cltbld', 01:30:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:38 INFO - 'XPC_FLAGS': '0x0', 01:30:38 INFO - 'XPC_SERVICE_NAME': '0', 01:30:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:30:38 INFO - Cleaning up... 01:30:38 INFO - Return code: 0 01:30:38 INFO - Running post-action listener: _resource_record_post_action 01:30:38 INFO - Running post-action listener: _start_resource_monitoring 01:30:38 INFO - Starting resource monitoring. 01:30:38 INFO - [mozharness: 2017-05-08 08:30:38.609711Z] Finished create-virtualenv step (success) 01:30:38 INFO - [mozharness: 2017-05-08 08:30:38.610695Z] Running install step. 01:30:38 INFO - Running pre-action listener: _resource_record_pre_action 01:30:38 INFO - Running main action method: install 01:30:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:30:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:30:38 INFO - Reading from file tmpfile_stdout 01:30:38 INFO - Detecting whether we're running mozinstall >=1.0... 01:30:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:30:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:30:38 INFO - Reading from file tmpfile_stdout 01:30:38 INFO - Output received: 01:30:38 INFO - Usage: mozinstall [options] installer 01:30:38 INFO - Options: 01:30:38 INFO - -h, --help show this help message and exit 01:30:38 INFO - -d DEST, --destination=DEST 01:30:38 INFO - Directory to install application into. [default: 01:30:38 INFO - "/builds/slave/test"] 01:30:38 INFO - --app=APP Application being installed. [default: firefox] 01:30:38 INFO - mkdir: /builds/slave/test/build/application 01:30:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:30:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 01:30:59 INFO - Reading from file tmpfile_stdout 01:30:59 INFO - Output received: 01:30:59 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 01:30:59 INFO - Running post-action listener: _resource_record_post_action 01:30:59 INFO - [mozharness: 2017-05-08 08:30:59.603278Z] Finished install step (success) 01:30:59 INFO - [mozharness: 2017-05-08 08:30:59.603419Z] Running run-tests step. 01:30:59 INFO - Running pre-action listener: _resource_record_pre_action 01:30:59 INFO - Running main action method: run_tests 01:30:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:30:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:30:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:30:59 INFO - 2017-05-08 01:30:59.617 screenresolution[1638:9660] starting screenresolution argv=screenresolution get 01:30:59 INFO - 2017-05-08 01:30:59.620 screenresolution[1638:9660] Display 0: 1600x1200x32@60 01:30:59 INFO - 2017-05-08 01:30:59.626 screenresolution[1639:9662] starting screenresolution argv=screenresolution list 01:30:59 INFO - Available Modes on Display 0 01:30:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:30:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:30:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:30:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:30:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:30:59 INFO - Intel Iris: 01:30:59 INFO - Chipset Model: Intel Iris 01:30:59 INFO - Type: GPU 01:30:59 INFO - Bus: Built-In 01:30:59 INFO - VRAM (Dynamic, Max): 1536 MB 01:30:59 INFO - Vendor: Intel (0x8086) 01:30:59 INFO - Device ID: 0x0a2e 01:30:59 INFO - Revision ID: 0x0009 01:30:59 INFO - Displays: 01:30:59 INFO - Display: 01:30:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:30:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:30:59 INFO - Display Serial Number: 1600x1200 60 01:30:59 INFO - Main Display: Yes 01:30:59 INFO - Mirror: Off 01:30:59 INFO - Online: Yes 01:30:59 INFO - Rotation: Supported 01:30:59 INFO - Return code: 0 01:30:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:30:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:30:59 INFO - Python 2.7.3 01:30:59 INFO - Return code: 0 01:30:59 INFO - grabbing minidump binary from tooltool 01:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:59 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 0x10c97ed30>, '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 0x10c8a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c973be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:30:59 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:30:59 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 01:30:59 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 01:30:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:30:59 INFO - Return code: 0 01:30:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:30:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:30:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:30:59 INFO - ENV: RUST_BACKTRACE is now 1 01:30:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:30:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:30:59 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/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0072', '--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 01:30:59 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/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0072 --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 01:30:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LocHGaiKhf/Render', 01:30:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:59 INFO - 'HOME': '/Users/cltbld', 01:30:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:59 INFO - 'LOGNAME': 'cltbld', 01:30:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:30:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:59 INFO - 'MOZ_NO_REMOTE': '1', 01:30:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:30:59 INFO - 'NO_EM_RESTART': '1', 01:30:59 INFO - 'PAGER': '/bin/cat', 01:30:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:59 INFO - 'PWD': '/builds/slave/test', 01:30:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:30:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:59 INFO - 'RUST_BACKTRACE': '1', 01:30:59 INFO - 'SHELL': '/bin/bash', 01:30:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xX9IpPzt2V/Listeners', 01:30:59 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 01:30:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:59 INFO - 'USER': 'cltbld', 01:30:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:00 INFO - 'XPC_FLAGS': '0x0', 01:31:00 INFO - 'XPC_SERVICE_NAME': '0', 01:31:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:31:00 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/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0072', '--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 01:31:00 INFO - mozversion application_buildid: 20170508004016 01:31:00 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:31:00 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:31:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:31:00 INFO - mozversion application_name: Firefox 01:31:00 INFO - mozversion application_remotingname: firefox-dev 01:31:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:31:00 INFO - mozversion application_vendor: Mozilla 01:31:00 INFO - mozversion application_version: 54.0a2 01:31:00 INFO - mozversion platform_buildid: 20170508004016 01:31:00 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:31:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:31:00 INFO - mozversion platform_version: 54.0a2 01:31:00 INFO - using testdate: 1494232260 01:31:00 INFO - actual date: 1494232260 01:31:00 INFO - starting webserver on 'localhost:49241' 01:31:00 INFO - SUITE-START | Running 2 tests 01:31:00 INFO - TEST-START | dromaeo_css 01:31:00 INFO - operating with platform_type : mac_ 01:31:00 INFO - Initialising browser for dromaeo_css test... 01:31:00 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpxUhnq3/profile http://localhost:49241/getInfo.html) 01:31:02 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 01:31:02 INFO - PROCESS | 1649 | colorDepth:24 01:31:02 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 01:31:02 INFO - PROCESS | 1649 | __metrics 01:31:02 INFO - PROCESS | 1649 | 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" 01:31:02 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:31:03 INFO - TEST-INFO | 1649: exit 0 01:31:03 INFO - Browser initialized. 01:31:03 INFO - Running cycle 1/1 for dromaeo_css test... 01:31:03 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpxUhnq3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:31:15 INFO - PROCESS | 1653 | RSS: Main: 210464768 01:31:15 INFO - PROCESS | 1653 | 01:34:20 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 01:34:22 INFO - PROCESS | 1653 | RSS: Main: 360173568 01:34:22 INFO - PROCESS | 1653 | 01:36:05 INFO - PROCESS | 1653 | ************************* 01:36:05 INFO - PROCESS | 1653 | A coding exception was thrown and uncaught in a Task. 01:36:05 INFO - PROCESS | 1653 | 01:36:05 ERROR - PROCESS | 1653 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:36:05 INFO - PROCESS | 1653 | Full stack: 01:36:05 INFO - PROCESS | 1653 | ************************* 01:36:05 INFO - PROCESS | 1653 | ************************* 01:36:05 INFO - PROCESS | 1653 | A coding exception was thrown and uncaught in a Task. 01:36:05 INFO - PROCESS | 1653 | 01:36:05 ERROR - PROCESS | 1653 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:36:05 INFO - PROCESS | 1653 | Full stack: 01:36:05 INFO - PROCESS | 1653 | ************************* 01:36:05 INFO - PROCESS | 1653 | ************************* 01:36:05 INFO - PROCESS | 1653 | A coding exception was thrown and uncaught in a Task. 01:36:05 INFO - PROCESS | 1653 | 01:36:05 ERROR - PROCESS | 1653 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:36:05 INFO - PROCESS | 1653 | Full stack: 01:36:05 INFO - PROCESS | 1653 | ************************* 01:36:05 INFO - PROCESS | 1653 | ************************* 01:36:05 INFO - PROCESS | 1653 | A coding exception was thrown and uncaught in a Task. 01:36:05 INFO - PROCESS | 1653 | 01:36:05 ERROR - PROCESS | 1653 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:36:05 INFO - PROCESS | 1653 | Full stack: 01:36:05 INFO - PROCESS | 1653 | ************************* 01:37:25 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 01:37:26 INFO - PROCESS | 1653 | RSS: Main: 343769088 01:37:26 INFO - PROCESS | 1653 | 01:40:28 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 01:40:29 INFO - PROCESS | 1653 | RSS: Main: 330330112 01:40:29 INFO - PROCESS | 1653 | 01:43:31 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 01:43:32 INFO - PROCESS | 1653 | RSS: Main: 324059136 01:43:32 INFO - PROCESS | 1653 | 01:46:36 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 01:46:36 INFO - PROCESS | 1653 | RSS: Main: 325074944 01:46:36 INFO - PROCESS | 1653 | 01:49:40 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 01:49:40 INFO - PROCESS | 1653 | RSS: Main: 371576832 01:49:40 INFO - PROCESS | 1653 | 01:49:40 INFO - PROCESS | 1653 | __start_tp_report 01:49:40 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 01:49:40 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 01:49:40 INFO - PROCESS | 1653 | |i|pagename|runs| 01:49:40 INFO - PROCESS | 1653 | |0;dojo.html;3404.3531728971493;3409.369266685663;3418.1112910643224;3424.982875085624;3471.080163756605;5896.970515147424;5898.1447690084915;5907.6750778707155;5934.406559344069;5937.435943585352;5874.588778785486;5917.615355001922;5943.910841337383;5984.401559844014;5985.311689155748;5339.4126646069;5357.571394288448;5433.4566543345545;5450.672959622435;5470.480304371078;5257.947420525803;5285;5318;5481.314835645544;5482.725863706819;5348.598855085865;5371.382291036542;5371.462853714636;5378.650387724805;5383.219433182206;5365.704886231256;5376.838694839161;5378.569714422837;5382.327209098863;5405.378381486121;5191.5846732261325;5316.20256961455;5318.813841515528;5338.652987555796;5356.571474282048;5903.439173278532;5905.793297234576;5912.113183022538;5914;5916.0238560637445;6256.468200202989;6296.905546416808;6306.211723534559;6311.747530098791;6312.842178945517;6311.779087731908;6357.300696923335;6391.201099862517;6404.519661025395;6418.390252925965;5240.816571419982;6066.302375226861;6067.666278354689;6076.210092687922;6102.603330783485;6309.116723658692;6451.387118223808;6497.512686548528;6509.674516274167;6516.1203237562795;6098.237720284965;6201.255849298113;6201.565890387629;6208.255009398901;6217.098520714471;4900.0845254613105;6301;6313.873722525524;6354.809355719336;6372.362763723683;6374.649394283359;6376.075469056961;6376.7130479128555;6381.010943303795;6386.233651961795;6351.333110023381;6353.777617783356;6354.555181137369;6360.459360954278;6367.872642547216;4968.30443737877;5005.149124648746;5018;5070.797168113234;5107.080725469377;4558.886027849264;4561.863144105682;4574.222382195035;4576.839810606612;4584.381108550303;5899.557533184942;5939.70301484924;5940.138679891478;5945.583809133319;5958.106284057426;6276.592021518587;6337.96831015842;6413.45485633717;6423.100765892778;6430.678466076677;5767.250257466587;5776.624519406225;5783.479486846204;5784.942150578525;5791.247137872049;5523.337199536081;5538.6122971391615;5539.667619942817;5543.417941116206;5549.19536667181;6307.432331090224;6328.71520781566;6337.493000559852;6360.045993101072;6360.204974378295;6308.211473565804;6329.715162817776;6382.0745991831855;6382.936170638234;6385.584936979081;6526.608403495806;6534.052562378523;6537.477001839937;6538.803835884931;6555.541112122106;6368.331325210786;6370.1718776558755;6377.17096777416;6381.202349706286;6384.744610215539;6243.656598887105;6272.435480806749;6308.14839996613;6318.557700960889;6320.462760665211;6309.463695585743;6311.084892690443;6324.272708638381;6329.082283068838;6329.683515824282;6337.144485494399;6339.778107766206;6349.460295874717;6350.555461117678;6384.840378990562;5921.289445266423;5986.790462333797;6029.60807547508;6110.43725517484;6182.412670796267;6252.124702541557;6308.52686048539;6334.588251763621;6344.921363368331;6349.365063493614;5151.459096794782;6161.045038019114;6162.383761623981;6174.598651087665;6186.443220110212 01:49:40 INFO - PROCESS | 1653 | |1;ext.html;735.4851603877286;806.7781360125966;849.5667209723043;871.298604623278;872.6422166911572;37656;38341;38400.42399364005;38407;38475.230495390075;38687.806560967154;38890.416643750366;38956.61043389565;38975.02562435933;38983;7327.890081648779;9026.007139214698;9173.082691730808;9178.816423671522;9198.402103863262;7318.231585683507;9744.951275243613;11111.944440277788;11182.944085279561;11220.326780393161;11059.612913548028;11073.446327683623;11076.169287303444;11082.667519974413;11109.500072496758;11011.229213954986;11011.229213954986;11036;11045.613403530839;11047;10597.682069537848;10658.200634952409;10659.573617055308;10661.84007239892;10664.840027399596;36906.63093369086;37106.81446592777;37204;37213.06967325811;37228.81385593082;3430.5540279763472;3777.0934975605655;3806.71449641278;3837.319954362688;3838.328292548793;24548.386290342707;26287.948482060696;27269.045583404484;27299.044533441232;27299.590506142424;2997.640283166012;3034.347615262729;3039.863206155728;3043.5130379139455;3046.8933587324336;331642.34178829193;332038;332535;333563;335184;49911.50088499141;50048.74878128076;50125.74937125291;50183.49816501861;50249;51243.74378128086;51479.485205147466;51518;51823;51886;14193.78709319361;14313.427462901576;14329.996900216885;14337.211453370164;14349.56951291463;12562.371881405894;12562.623121306375;12573.87426125727;12574.056945729108;12576.496940122475;11032.558697652004;11560.421978901022;11644;11702;11703.765924681458;5518.779248830002;5658.292713410824;5674;5680.403557626472;5683.232763576947;844.7001314533375;1096.331237944853;1099.741560733227;1125.1673761416494;1183.408295852074;20222.696659550118;20753.481162971046;24913.50172996566;24966.502009879103;25858.83635236419;802.6468353924255;850.8723691446332;1014.2241185493103;1047.1413440978477;1146.6158836789602;13861.306934653227;14158.362873670709;14200.928995354958;14442.422303107758;14540.927295363666;423540;425297;426751;427028;427046;43840;53448.465515344345;53805.1929221062;53891.730541347046;53952.730236348565;1271.5041133957764;1430.6780974280912;1460.1093333066822;1463.0855715177802;1464.970700585961;27907.44185116327;28395.148145555668;28971.42057158887;28983.5652465209;29048.56427153552;39053;41660.79169604133;42480.937976550835;44018.559814401444;44048;399453;399687;402185;403024;403583;27722.584161238003;27909.720902791523;27945.72054279431;27958.440831182856;27978.160655180378;35594.46608300826;35632.46551301681;36207.913762587166;36343.818280908425;36668.26663466769;25881.870590646926;26225.475490490466;26319.07883224078;26690.866545667148;27044.37566553581;18423.263069477605;20535;26030.73969260283;26118.477630447665;26773.866130669223;25362.74637253604;25756;30414.84792576023;30792.84603576968;30804.22989425282;24088.15691450791;24606.507869842862;27438.725612744416;27677.169684909484;27921.16236512908;20942.685859712423;21655.675164872853;21801.454963626034;21870;21918.45203869916 01:49:40 INFO - PROCESS | 1653 | |2;jquery.html;385.39877790646614;409.84016233668876;419.8005947175092;424.7451529082551;447.8902668846133;10263.281570290073;14425.495107671233;20403.795962040374;20722.481937951557;21048.263310784125;21177.258795942143;21416.892915535438;22133.66799498009;22142.88928555355;22423.551528969412;6542.214934207902;6548.934510654892;6713.496487763413;6801.387875091241;6824.761133360334;4087.2234275487563;4229.830806767725;6612.669366531678;6715.059891615178;6739.595624262535;4222.641075508585;7638.388928885676;7715.035620547432;7763.029621297338;7767.883481747778;6207.937920620826;7622.085349758009;7688.231176882323;7702.730404435846;7716.074071111474;7171.390431813302;7318.3047610476915;7415;7437.479376443597;7443.069616297963;26009.21972340833;26156.215313540622;26171.73828261712;26269.605955910676;26351.868240658867;2876.5828954801436;2966.110166949928;2998.8200707957376;2998.9400211995858;3002.5287609071397;16050.678986420324;16121.113338766363;16265.512034638861;16335.9381640193;16898;1917.8753381030187;2038.0129373860516;2071.1301253473575;2108.704781330615;2148.785121487854;101920;103190.22805846402;112082;124507.3774631121;128924.7107528922;19884.50288742793;20798.27206047781;20890.686639700416;20926.37220883376;21083.578328433345;33237.50143747846;33287.83356083204;33306.66773329088;33378.83310583432;33460.83269583636;10653.84019239712;10716.410597417216;10869.565217391373;10874.130069594572;10980.396078215615;7261.491695581364;8790.516521591246;8835.425697329652;8890.822183556293;9007.414518056305;9754.290653167058;10019.398836069859;10111.898881011242;10147.955613315975;10158.39049657023;4045.37296719009;4082.7346222495444;4121.814518346621;4129.855455059059;4136.627703506699;929.4005366538545;1023.5649848814224;1031.2832581356035;1033.0289527843802;1035.249444152983;18695.532611684815;20994.79005209959;21277.148914043264;21430.357089287347;21465.89267053655;1029.8281293615646;1048.491481631408;1060.2419270221828;1073.382804887193;1083.9891601084046;10710.46447677613;11566.826497602542;11573.76852462946;11607.245529040754;11610.303381797119;723489;731296;750450;766647;770449;102678.48660756797;123001.76998229903;123657;126787.73212267943;128067;1275.6938334799581;1315.5395611536078;1348.447136673959;1351.8850897673808;1355.735631551838;19006.524836878634;19141.521461963006;19374.70937935904;19519.512012199808;19565.413037608894;45157.871053223935;64122.03816942656;65120.69758604896;65865;67857;92612;92873.60689589797;93535.53232233795;95168;95750;9314.25485961108;11309.264897781617;11541;11767.23512971654;11858.11064170208;12275.324857132944;12445;12625.936870315956;12869.871301286867;12957.963362931132;10403.323783954018;10847.294925830112;11021.614243501439;11196.94401527987;11207;9161.175494205287;9372.109649583279;10326.690199294033;10339.844902326622;10428.32215905994;10801.567937282422;10841.728956775829;10877.728056798644;10961.671149865517;11275.830862537232;11040.668779936615;11056.447177640925;11230.3261804292;11265.493052812479;11471.483783229607;9769.364991275545;10779.892201078203;10936.726581835517;10985.121190304917;11017.669469915916 01:49:40 INFO - PROCESS | 1653 | |3;mootools.html;1393.3312010235086;1544.9691006179883;1552.200616682408;1571.3321838218762;1578.739507981183;10483.842742358862;10950.233483656137;10951.890481095186;10956.068734157596;10976;10589.311694739836;10778.029977302042;10921.617743378984;10957.56169753209;10997.61508347208;1983.3157560641591;2358.740538540759;2359.1035406545575;2360.492494113765;2361.763823617633;2259.378670865517;2330.1611419888827;2342.8360014798973;2349.259983105316;2351.9176828810996;2303.9424014399606;2314.7569505201964;2330.592146374386;2344.23812261015;2352.3178278299274;2260.5930932432157;2310.364649721323;2312.225404489498;2323.6282194848827;2339.4619237575284;2330.976690233093;2332.043862016582;2336.100601268524;2337.9298621041394;2339.5320935812733;11016.834747478793;11080.889191108066;11092.223544351898;11094.55621775136;11100.834412386745;5474.589405794582;5651.2935883014625;5654.095344744862;5663.9716801415725;5667.3482549506925;3255.788373755698;3353.724001897576;3518.630543792916;3521.6126226115107;3528.153243221634;1103.2340289947351;1131.7906187355366;1153.1293873125737;1153.3656488931053;1172.7478592102739;13341;13776.311184440738;13935.860641393558;15082.849171508255;15136.091834489966;4379.64962802975;4525.773711314421;4543.363929049936;4544.931826022612;4581.74800385982;11508.482118304697;11511.884881151082;11523.366214858263;11537.250078744855;11538.134639901873;4713.34013238147;4902.1911384621135;5290.9735451322495;5442.537384322298;5446.782128714807;5047.87380315495;5113.71874546896;5117.2068329409;5125.359330083739;5130.743462826844;3698.167912219783;3746.138388170736;3799.696024318048;3806.676432503268;3819.3125237457525;948.7059011706393;954.3271993244746;968.5786682792867;969.6363863551169;986.4377304936118;926.9953650231706;950.3632566180676;952.4333021851987;957.1481381570408;1005.4570531912685;6589.86820263592;6599.670016499252;6690.431313338421;6842.965785171043;6853.314668533107;878.6178012564554;1042.6819819954835;1055.2718624149313;1085.478970094359;1096.6929259807266;4145.834166633301;4437.889052773707;4453.153900758845;4525.117602067565;4535.795889184995;13769.518066867611;14195.503157389649;14260.35828387725;14261.001729879017;14269.500567480089;12025.4588543514;13347.799783003262;14111.576652700436;14144.36350364236;14168.716625667636;9812.215022798158;10082.899171008195;10098.747531311776;10099.293049486612;10118.645847395306;5512.862178445571;6778.8983165251575;6792.864142717019;6953.478489113438;7028.683709233199;11757;12151.696207594881;12461.87538124607;12547.560835370718;12564.057695672678;9548.713538593853;11293.983541481306;11585.768284634429;12353.567625133077;12372.257664540157;8533.359998000298;8631.654733810577;8874.51190184546;9045.095490450902;9254.629814807333;10001;10043.949780251052;10163.441010744184;10165.085142337226;10255.230857685554;7255.963720181366;8579.35654825878;8579.742607721779;8599.699010534601;8610.61252243638;8481.194286542768;8536.317094632539;8539.74380768578;8548.358873084419;8551.957240213758;7717.922820771721;7775.650095745818;7899.486533375312;7902.723404680809;7909.564973926259;5843.386444423444;5902.468777810018;5979.5814292999075;6039.577229593886;6040.486558642565;5850.970745146247;5941.31674857397;5978.282606087298;6057.939420605914;6159.692015399301 01:49:40 INFO - PROCESS | 1653 | |4;prototype.html;324.44519871020555;369.25042164406267;373.57785702156565;378.92800367930107;379.66779068315225;4548.454185497737;4970.204767237243;5069.61727987846;5121.462246464123;5131.588813076406;4186.476690413699;4220.577942205778;4222.619964203221;4500.369948207245;4948.653594248404;3538.982779815506;3748.3707621087933;4018.216447792689;4132.917341653165;4183.937240941388;4024.7182697211206;4104.97947510262;4132.814023368941;4161.375793630965;4221.915561688765;4188;4231.830726770925;4244.299690551071;4247.25673007224;4276.2894842063115;3642.7814331139953;3732.1789206374556;3896.9478240875087;4223.086070611268;4300.870973870805;3429.9314013719586;3849.480320156771;3857.961420385788;3870.6709929656013;3875.6899448044087;4883.511648835123;4902.754862256873;4922.203395760557;4942.975285123552;4962.975185124087;3856.9421458678144;4564.7032942858605;4661.645157759649;4727.598154156901;4744.264638980963;4592.7933243004145;4866.836556238438;4968.3292755477905;5047;5234.319538459976;3833.5591406988274;4120.835166593332;4125.339945608688;4278.893027674302;4297.269464191095;5317.680939143664;5419.160030195326;5484.669015668325;5570.888582228374;5689.687067211302;3672.137047793766;3942.8619998300496;4092.4066010428915;4388.539203383599;4407.933880991788;3927.823247953792;4480.401367207012;4500;4524.484297760221;4543.704659197141;4152.231837110145;4813.638977076664;4820.939393333461;4845.442774080955;4869.732164730903;4510.751908645056;4744.686218546478;4941.75291235444;5088.92366614501;5269.868253293698;1279.6161151654467;1382.5644921849585;1506.691128318692;1512.0947606016498;1521.9086854788527;1068.8830172469789;1073.7154654016692;1076.924615276923;1126.6620013995769;1142.6971852459105;4390.47314322277;4633.235516139866;4954.894169977817;4964.304997300381;5024.296598476216;5376.247325374451;5504.889902201933;5529.751161197756;5579.832605021856;5630.352509461382;4311.4395128633705;4405.691601587858;4474.664400170001;4887.584555312838;4964.503549645079;2459.4835084632427;2629.4346715456268;2828.43431313734;2866.7706583473273;2902.3324635333993;5099.745012749348;5126.436092029874;5641.633293835969;5706.229658996065;5724.8282551523525;4902.950970490249;5025.120603894304;5333.146696528537;5493.835184944458;5622.437756224426;4797.56821886032;5369.288509302777;5384;5569.999999999919;5754.798582049608;4889.779959901742;4968.27959945813;5500.312460942382;5550.833474995757;5567.246317410003;4494.662900282427;5123.386133367977;5180.161042829892;5476.645042631707;5514.834554963358;4765.73788441639;4837.55703369796;5023.598112151092;5621.915671264853;5686.573506987075;4079.265732168238;4782.306565548046;4807.106243942418;4811.927821082617;4821.9035619286715;4465.5087940327185;5247.9737601311745;5443.503036664949;5520.089185284383;5558.664241891332;4590.816367345269;4690.577847993697;5121.667091639031;5162.457941916045;5338.652987555952;4662.743549104832;4749.881252968704;4765.8093676252565;5313.990341835039;5314.468553144655;4493.752843593634;4546.704464209815;4671.976640116778;5008.398992120969;5264.657797243167;4604.178531818649;5290.629655924048;5339.211364193039;5340.759665815125;5356.035913535601;5228.843134705965;5237.607179461632;5259.4477579853565;5276.314079169683;5278.604104692086 01:49:40 INFO - PROCESS | 1653 | |5;yui.html;712.8253577873422;735.9668814903332;768.2010708862783;815.2458975447711;827.300930713547;15318.234088295583;16123.83876161238;16128.435504757335;16133.112678802661;16141.43504977326;14762.96659233854;14853;14903.776443353358;14921.477748278812;15257.542273731779;895.3463971300954;922.0733163171037;922.6908985489869;923.833709932212;944.7921457279388;920.1856357123927;940.8776859008319;941.4492521874712;943.3490891285026;944.3955868244329;990.2474119669031;990.5790039233333;990.9554070066828;993.8310487217154;994.0159242350094;985.4235272365675;987.9209663226925;988.4316518002141;989.1147423056324;989.8960609136082;734.6473692627516;735.1582438108336;735.2023054985348;735.8638651849371;736.690589952216;15933.283002264925;16174.75737863933;16217;16535.007899526066;16828.74756878648;13708.314584270747;13790.793138102936;13931;13957.79063314041;14189.929050354785;11697.532098716041;11702.473388697445;11912.821307680391;12003.099767517471;12031.518739250507;3626.4197728363597;3667.3215455140885;3674.6325367463305;3684.9866286771353;3800.600936901641;50526.108761923366;68815;100758;101010.49494752553;111835.44082279537;10608.628697995533;10675.73310667224;11040.834387484194;11086.833697494543;11149.721256968478;21455.141794328363;21821.23625673094;21824.345269641937;21874.343769686933;22023.11907523681;7853.136155022943;7913.881291780628;7957.442978991531;7964.163762804822;7967.9203207967175;4276.786160691953;4320.589543993316;4336.176126535948;4360.7601581913295;4374.0158464345905;3488.284901595167;3558.1994051338243;3570.785752854837;3588.2105936693883;3598.1544337081286;648.8734696734092;650.4341223135807;654.1267408010359;665.5740326191242;666.5167753378762;547.5236544206566;572.2703552969913;581.855861151649;588.9941100589025;603.8611119442465;10196.643117491001;10278.17774578032;10594.57621695139;10604.363738175734;10744.247902646895;682.6245564939263;683.2347770497075;685.7702669605638;709.5884387055495;713.4399496395305;4744.146053710365;4816.903661926812;5037.370328708911;5037.974810125926;5042.243663450512;100430.49354259692;104151.47924260482;104966.95033049725;105936;106775;75361;76027;83723.74414383789;84968;87624.56187719021;63137.737245255754;71323;73553;74092.62953685196;75938;8905.198532132139;9448.102430269113;9578.760530986781;9596.99231580674;9629.711108666752;41841.79079104707;43555.782221088695;47124.52875471202;48093.7976550589;51330;58283.70858145682;62081.06878396737;69062;69617.30382696108;71868;12334.259944403364;12682.682932926751;13012;13245.403956821774;13293.800592991307;13883.583492495241;14020.859791401956;14978.925105374403;15001;15051.247437628293;13298.335083245993;13750.793738093736;14033.29833508341;14035.228062456663;14518.056326338754;11709.941450292694;13188;13382.330883455983;13934.163950163023;14432.85567144315;12939.870601293867;13459.865401345862;13647.795283070564;14102.153870767786;14138.717225655224;9493;10087.646932357333;10231.641892533726;10252;10567.947160264457;10086.243531735003;10478.685639430829;10673.785179614451;11060.391678457761;11638.418079095842 01:49:40 INFO - PROCESS | 1653 | __end_tp_report 01:49:40 INFO - PROCESS | 1653 | __start_cc_report 01:49:40 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,2025 01:49:40 INFO - PROCESS | 1653 | __end_cc_report 01:49:40 INFO - PROCESS | 1653 | __startTimestamp1494233380769__endTimestamp 01:49:40 INFO - PROCESS | 1653 | 01:49:40 INFO - PROCESS | 1653 | ------- Summary: start ------- 01:49:40 INFO - PROCESS | 1653 | Number of tests: 6 01:49:40 INFO - PROCESS | 1653 | 01:49:40 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5884.28 Median:6161.05 stddev:655.69 (10.6%) stddev-sans-first:628.17 01:49:40 INFO - PROCESS | 1653 | Values: 3404.4 3409.4 3418.1 3425.0 3471.1 5897.0 5898.1 5907.7 5934.4 5937.4 5874.6 5917.6 5943.9 5984.4 5985.3 5339.4 5357.6 5433.5 5450.7 5470.5 5257.9 5285.0 5318.0 5481.3 5482.7 5348.6 5371.4 5371.5 5378.7 5383.2 5365.7 5376.8 5378.6 5382.3 5405.4 5191.6 5316.2 5318.8 5338.7 5356.6 5903.4 5905.8 5912.1 5914.0 5916.0 6256.5 6296.9 6306.2 6311.7 6312.8 6311.8 6357.3 6391.2 6404.5 6418.4 5240.8 6066.3 6067.7 6076.2 6102.6 6309.1 6451.4 6497.5 6509.7 6516.1 6098.2 6201.3 6201.6 6208.3 6217.1 4900.1 6301.0 6313.9 6354.8 6372.4 6374.6 6376.1 6376.7 6381.0 6386.2 6351.3 6353.8 6354.6 6360.5 6367.9 4968.3 5005.1 5018.0 5070.8 5107.1 4558.9 4561.9 4574.2 4576.8 4584.4 5899.6 5939.7 5940.1 5945.6 5958.1 6276.6 6338.0 6413.5 6423.1 6430.7 5767.3 5776.6 5783.5 5784.9 5791.2 5523.3 5538.6 5539.7 5543.4 5549.2 6307.4 6328.7 6337.5 6360.0 6360.2 6308.2 6329.7 6382.1 6382.9 6385.6 6526.6 6534.1 6537.5 6538.8 6555.5 6368.3 6370.2 6377.2 6381.2 6384.7 6243.7 6272.4 6308.1 6318.6 6320.5 6309.5 6311.1 6324.3 6329.1 6329.7 6337.1 6339.8 6349.5 6350.6 6384.8 5921.3 5986.8 6029.6 6110.4 6182.4 6252.1 6308.5 6334.6 6344.9 6349.4 5151.5 6161.0 6162.4 6174.6 6186.4 01:49:40 INFO - PROCESS | 1653 | 01:49:40 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:51685.77 Median:29016.06 stddev:103045.50 (355.1%) stddev-sans-first:103263.56 01:49:40 INFO - PROCESS | 1653 | Values: 735.5 806.8 849.6 871.3 872.6 37656.0 38341.0 38400.4 38407.0 38475.2 38687.8 38890.4 38956.6 38975.0 38983.0 7327.9 9026.0 9173.1 9178.8 9198.4 7318.2 9745.0 11111.9 11182.9 11220.3 11059.6 11073.4 11076.2 11082.7 11109.5 11011.2 11011.2 11036.0 11045.6 11047.0 10597.7 10658.2 10659.6 10661.8 10664.8 36906.6 37106.8 37204.0 37213.1 37228.8 3430.6 3777.1 3806.7 3837.3 3838.3 24548.4 26287.9 27269.0 27299.0 27299.6 2997.6 3034.3 3039.9 3043.5 3046.9 331642.3 332038.0 332535.0 333563.0 335184.0 49911.5 50048.7 50125.7 50183.5 50249.0 51243.7 51479.5 51518.0 51823.0 51886.0 14193.8 14313.4 14330.0 14337.2 14349.6 12562.4 12562.6 12573.9 12574.1 12576.5 11032.6 11560.4 11644.0 11702.0 11703.8 5518.8 5658.3 5674.0 5680.4 5683.2 844.7 1096.3 1099.7 1125.2 1183.4 20222.7 20753.5 24913.5 24966.5 25858.8 802.6 850.9 1014.2 1047.1 1146.6 13861.3 14158.4 14200.9 14442.4 14540.9 423540.0 425297.0 426751.0 427028.0 427046.0 43840.0 53448.5 53805.2 53891.7 53952.7 1271.5 1430.7 1460.1 1463.1 1465.0 27907.4 28395.1 28971.4 28983.6 29048.6 39053.0 41660.8 42480.9 44018.6 44048.0 399453.0 399687.0 402185.0 403024.0 403583.0 27722.6 27909.7 27945.7 27958.4 27978.2 35594.5 35632.5 36207.9 36343.8 36668.3 25881.9 26225.5 26319.1 26690.9 27044.4 18423.3 20535.0 26030.7 26118.5 26773.9 25362.7 25756.0 30414.8 30792.8 30804.2 24088.2 24606.5 27438.7 27677.2 27921.2 20942.7 21655.7 21801.5 21870.0 21918.5 01:49:40 INFO - PROCESS | 1653 | 01:49:40 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:41121.20 Median:20960.58 stddev:123481.35 (589.1%) stddev-sans-first:123789.86 01:49:40 INFO - PROCESS | 1653 | Values: 385.4 409.8 419.8 424.7 447.9 10263.3 14425.5 20403.8 20722.5 21048.3 21177.3 21416.9 22133.7 22142.9 22423.6 6542.2 6548.9 6713.5 6801.4 6824.8 4087.2 4229.8 6612.7 6715.1 6739.6 4222.6 7638.4 7715.0 7763.0 7767.9 6207.9 7622.1 7688.2 7702.7 7716.1 7171.4 7318.3 7415.0 7437.5 7443.1 26009.2 26156.2 26171.7 26269.6 26351.9 2876.6 2966.1 2998.8 2998.9 3002.5 16050.7 16121.1 16265.5 16335.9 16898.0 1917.9 2038.0 2071.1 2108.7 2148.8 101920.0 103190.2 112082.0 124507.4 128924.7 19884.5 20798.3 20890.7 20926.4 21083.6 33237.5 33287.8 33306.7 33378.8 33460.8 10653.8 10716.4 10869.6 10874.1 10980.4 7261.5 8790.5 8835.4 8890.8 9007.4 9754.3 10019.4 10111.9 10148.0 10158.4 4045.4 4082.7 4121.8 4129.9 4136.6 929.4 1023.6 1031.3 1033.0 1035.2 18695.5 20994.8 21277.1 21430.4 21465.9 1029.8 1048.5 1060.2 1073.4 1084.0 10710.5 11566.8 11573.8 11607.2 11610.3 723489.0 731296.0 750450.0 766647.0 770449.0 102678.5 123001.8 123657.0 126787.7 128067.0 1275.7 1315.5 1348.4 1351.9 1355.7 19006.5 19141.5 19374.7 19519.5 19565.4 45157.9 64122.0 65120.7 65865.0 67857.0 92612.0 92873.6 93535.5 95168.0 95750.0 9314.3 11309.3 11541.0 11767.2 11858.1 12275.3 12445.0 12625.9 12869.9 12958.0 10403.3 10847.3 11021.6 11196.9 11207.0 9161.2 9372.1 10326.7 10339.8 10428.3 10801.6 10841.7 10877.7 10961.7 11275.8 11040.7 11056.4 11230.3 11265.5 11471.5 9769.4 10779.9 10936.7 10985.1 11017.7 01:49:40 INFO - PROCESS | 1653 | 01:49:40 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:6618.65 Median:2359.80 stddev:4149.19 (175.8%) stddev-sans-first:4142.25 01:49:40 INFO - PROCESS | 1653 | Values: 1393.3 1545.0 1552.2 1571.3 1578.7 10483.8 10950.2 10951.9 10956.1 10976.0 10589.3 10778.0 10921.6 10957.6 10997.6 1983.3 2358.7 2359.1 2360.5 2361.8 2259.4 2330.2 2342.8 2349.3 2351.9 2303.9 2314.8 2330.6 2344.2 2352.3 2260.6 2310.4 2312.2 2323.6 2339.5 2331.0 2332.0 2336.1 2337.9 2339.5 11016.8 11080.9 11092.2 11094.6 11100.8 5474.6 5651.3 5654.1 5664.0 5667.3 3255.8 3353.7 3518.6 3521.6 3528.2 1103.2 1131.8 1153.1 1153.4 1172.7 13341.0 13776.3 13935.9 15082.8 15136.1 4379.6 4525.8 4543.4 4544.9 4581.7 11508.5 11511.9 11523.4 11537.3 11538.1 4713.3 4902.2 5291.0 5442.5 5446.8 5047.9 5113.7 5117.2 5125.4 5130.7 3698.2 3746.1 3799.7 3806.7 3819.3 948.7 954.3 968.6 969.6 986.4 927.0 950.4 952.4 957.1 1005.5 6589.9 6599.7 6690.4 6843.0 6853.3 878.6 1042.7 1055.3 1085.5 1096.7 4145.8 4437.9 4453.2 4525.1 4535.8 13769.5 14195.5 14260.4 14261.0 14269.5 12025.5 13347.8 14111.6 14144.4 14168.7 9812.2 10082.9 10098.7 10099.3 10118.6 5512.9 6778.9 6792.9 6953.5 7028.7 11757.0 12151.7 12461.9 12547.6 12564.1 9548.7 11294.0 11585.8 12353.6 12372.3 8533.4 8631.7 8874.5 9045.1 9254.6 10001.0 10043.9 10163.4 10165.1 10255.2 7256.0 8579.4 8579.7 8599.7 8610.6 8481.2 8536.3 8539.7 8548.4 8552.0 7717.9 7775.7 7899.5 7902.7 7909.6 5843.4 5902.5 5979.6 6039.6 6040.5 5851.0 5941.3 5978.3 6057.9 6159.7 01:49:40 INFO - PROCESS | 1653 | 01:49:40 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4414.70 Median:4765.77 stddev:1224.11 (25.7%) stddev-sans-first:1188.43 01:49:40 INFO - PROCESS | 1653 | Values: 324.4 369.3 373.6 378.9 379.7 4548.5 4970.2 5069.6 5121.5 5131.6 4186.5 4220.6 4222.6 4500.4 4948.7 3539.0 3748.4 4018.2 4132.9 4183.9 4024.7 4105.0 4132.8 4161.4 4221.9 4188.0 4231.8 4244.3 4247.3 4276.3 3642.8 3732.2 3896.9 4223.1 4300.9 3429.9 3849.5 3858.0 3870.7 3875.7 4883.5 4902.8 4922.2 4943.0 4963.0 3856.9 4564.7 4661.6 4727.6 4744.3 4592.8 4866.8 4968.3 5047.0 5234.3 3833.6 4120.8 4125.3 4278.9 4297.3 5317.7 5419.2 5484.7 5570.9 5689.7 3672.1 3942.9 4092.4 4388.5 4407.9 3927.8 4480.4 4500.0 4524.5 4543.7 4152.2 4813.6 4820.9 4845.4 4869.7 4510.8 4744.7 4941.8 5088.9 5269.9 1279.6 1382.6 1506.7 1512.1 1521.9 1068.9 1073.7 1076.9 1126.7 1142.7 4390.5 4633.2 4954.9 4964.3 5024.3 5376.2 5504.9 5529.8 5579.8 5630.4 4311.4 4405.7 4474.7 4887.6 4964.5 2459.5 2629.4 2828.4 2866.8 2902.3 5099.7 5126.4 5641.6 5706.2 5724.8 4903.0 5025.1 5333.1 5493.8 5622.4 4797.6 5369.3 5384.0 5570.0 5754.8 4889.8 4968.3 5500.3 5550.8 5567.2 4494.7 5123.4 5180.2 5476.6 5514.8 4765.7 4837.6 5023.6 5621.9 5686.6 4079.3 4782.3 4807.1 4811.9 4821.9 4465.5 5248.0 5443.5 5520.1 5558.7 4590.8 4690.6 5121.7 5162.5 5338.7 4662.7 4749.9 4765.8 5314.0 5314.5 4493.8 4546.7 4672.0 5008.4 5264.7 4604.2 5290.6 5339.2 5340.8 5356.0 5228.8 5237.6 5259.4 5276.3 5278.6 01:49:40 INFO - PROCESS | 1653 | 01:49:40 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:19541.13 Median:3742.79 stddev:27340.08 (730.5%) stddev-sans-first:27380.22 01:49:40 INFO - PROCESS | 1653 | Values: 712.8 736.0 768.2 815.2 827.3 15318.2 16123.8 16128.4 16133.1 16141.4 14763.0 14853.0 14903.8 14921.5 15257.5 895.3 922.1 922.7 923.8 944.8 920.2 940.9 941.4 943.3 944.4 990.2 990.6 991.0 993.8 994.0 985.4 987.9 988.4 989.1 989.9 734.6 735.2 735.2 735.9 736.7 15933.3 16174.8 16217.0 16535.0 16828.7 13708.3 13790.8 13931.0 13957.8 14189.9 11697.5 11702.5 11912.8 12003.1 12031.5 3626.4 3667.3 3674.6 3685.0 3800.6 50526.1 68815.0 100758.0 101010.5 111835.4 10608.6 10675.7 11040.8 11086.8 11149.7 21455.1 21821.2 21824.3 21874.3 22023.1 7853.1 7913.9 7957.4 7964.2 7967.9 4276.8 4320.6 4336.2 4360.8 4374.0 3488.3 3558.2 3570.8 3588.2 3598.2 648.9 650.4 654.1 665.6 666.5 547.5 572.3 581.9 589.0 603.9 10196.6 10278.2 10594.6 10604.4 10744.2 682.6 683.2 685.8 709.6 713.4 4744.1 4816.9 5037.4 5038.0 5042.2 100430.5 104151.5 104967.0 105936.0 106775.0 75361.0 76027.0 83723.7 84968.0 87624.6 63137.7 71323.0 73553.0 74092.6 75938.0 8905.2 9448.1 9578.8 9597.0 9629.7 41841.8 43555.8 47124.5 48093.8 51330.0 58283.7 62081.1 69062.0 69617.3 71868.0 12334.3 12682.7 13012.0 13245.4 13293.8 13883.6 14020.9 14978.9 15001.0 15051.2 13298.3 13750.8 14033.3 14035.2 14518.1 11709.9 13188.0 13382.3 13934.2 14432.9 12939.9 13459.9 13647.8 14102.2 14138.7 9493.0 10087.6 10231.6 10252.0 10567.9 10086.2 10478.7 10673.8 11060.4 11638.4 01:49:40 INFO - PROCESS | 1653 | -------- Summary: end -------- 01:49:40 INFO - PROCESS | 1653 | 01:49:41 INFO - TEST-INFO | 1653: exit 0 01:49:41 INFO - TEST-OK | dromaeo_css | took 1121199ms 01:49:41 INFO - TEST-START | kraken 01:49:41 INFO - operating with platform_type : mac_ 01:49:41 INFO - Initialising browser for kraken test... 01:49:41 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpMYvZRh/profile http://localhost:49241/getInfo.html) 01:49:43 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 01:49:43 INFO - PROCESS | 1658 | colorDepth:24 01:49:43 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 01:49:43 INFO - PROCESS | 1658 | __metrics 01:49:43 INFO - PROCESS | 1658 | 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" 01:49:43 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:49:44 INFO - TEST-INFO | 1658: exit 0 01:49:44 INFO - Browser initialized. 01:49:44 INFO - Running cycle 1/1 for kraken test... 01:49:44 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpMYvZRh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:49:56 INFO - PROCESS | 1660 | RSS: Main: 214822912 01:49:56 INFO - PROCESS | 1660 | 01:49:59 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 01:50:00 INFO - PROCESS | 1660 | RSS: Main: 268439552 01:50:00 INFO - PROCESS | 1660 | 01:50:03 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 01:50:03 INFO - PROCESS | 1660 | RSS: Main: 279142400 01:50:03 INFO - PROCESS | 1660 | 01:50:07 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 01:50:07 INFO - PROCESS | 1660 | RSS: Main: 376356864 01:50:07 INFO - PROCESS | 1660 | 01:50:09 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 01:50:10 INFO - PROCESS | 1660 | RSS: Main: 278097920 01:50:10 INFO - PROCESS | 1660 | 01:50:12 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:50:13 INFO - PROCESS | 1660 | RSS: Main: 279650304 01:50:13 INFO - PROCESS | 1660 | 01:50:16 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 01:50:16 INFO - PROCESS | 1660 | RSS: Main: 320262144 01:50:16 INFO - PROCESS | 1660 | 01:50:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 01:50:20 INFO - PROCESS | 1660 | RSS: Main: 322830336 01:50:20 INFO - PROCESS | 1660 | 01:50:23 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 01:50:24 INFO - PROCESS | 1660 | RSS: Main: 319438848 01:50:24 INFO - PROCESS | 1660 | 01:50:25 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:50:26 INFO - PROCESS | 1660 | RSS: Main: 296108032 01:50:26 INFO - PROCESS | 1660 | 01:50:28 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:50:28 INFO - PROCESS | 1660 | RSS: Main: 297938944 01:50:28 INFO - PROCESS | 1660 | 01:50:31 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:50:31 INFO - PROCESS | 1660 | RSS: Main: 303902720 01:50:31 INFO - PROCESS | 1660 | 01:50:34 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:50:35 INFO - PROCESS | 1660 | RSS: Main: 320290816 01:50:35 INFO - PROCESS | 1660 | 01:50:38 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:50:38 INFO - PROCESS | 1660 | RSS: Main: 313638912 01:50:38 INFO - PROCESS | 1660 | 01:50:40 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 01:50:41 INFO - PROCESS | 1660 | RSS: Main: 357675008 01:50:41 INFO - PROCESS | 1660 | 01:50:41 INFO - PROCESS | 1660 | __start_tp_report 01:50:41 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 01:50:41 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 01:50:41 INFO - PROCESS | 1660 | |i|pagename|runs| 01:50:41 INFO - PROCESS | 1660 | |0;ai-astar;158;171;125;123;168;161;117;116;117;116 01:50:41 INFO - PROCESS | 1660 | |1;audio-beat-detection;143;130;111;142;111;108;108;108;129;110 01:50:41 INFO - PROCESS | 1660 | |2;audio-dft;185;185;185;187;183;185;185;186;185;186 01:50:41 INFO - PROCESS | 1660 | |3;audio-fft;82;62;58;93;60;63;75;82;98;78 01:50:41 INFO - PROCESS | 1660 | |4;audio-oscillator;88;70;87;70;69;68;83;69;69;82 01:50:41 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;90;89;89;88;89;90;89;130;90;89 01:50:41 INFO - PROCESS | 1660 | |6;imaging-darkroom;122;97;95;96;96;96;97;95;110;150 01:50:41 INFO - PROCESS | 1660 | |7;imaging-desaturate;84;86;85;86;84;85;86;86;110;129 01:50:41 INFO - PROCESS | 1660 | |8;json-parse-financial;62;62;62;61;65;64;92;96;75;63 01:50:41 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;63;57;54;66;59;59;57;59;57;59 01:50:41 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;85;83;85;85;84;92;99;101;98;84 01:50:41 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;137;138;137;136;134;136;141;145;196;170 01:50:41 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;164;164;162;169;164;163;164;164;166;167 01:50:41 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;64;67;70;80;86;80;81;79;77;64 01:50:41 INFO - PROCESS | 1660 | __end_tp_report 01:50:41 INFO - PROCESS | 1660 | __start_cc_report 01:50:41 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,2165 01:50:41 INFO - PROCESS | 1660 | __end_cc_report 01:50:41 INFO - PROCESS | 1660 | __startTimestamp1494233441085__endTimestamp 01:50:41 INFO - PROCESS | 1660 | 01:50:41 INFO - PROCESS | 1660 | ------- Summary: start ------- 01:50:41 INFO - PROCESS | 1660 | Number of tests: 14 01:50:41 INFO - PROCESS | 1660 | 01:50:41 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:137.20 Median:141.50 stddev:23.93 (16.9%) stddev-sans-first:24.17 01:50:41 INFO - PROCESS | 1660 | Values: 158.0 171.0 125.0 123.0 168.0 161.0 117.0 116.0 117.0 116.0 01:50:41 INFO - PROCESS | 1660 | 01:50:41 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:120.00 Median:120.00 stddev:14.48 (12.1%) stddev-sans-first:12.75 01:50:41 INFO - PROCESS | 1660 | Values: 143.0 130.0 111.0 142.0 111.0 108.0 108.0 108.0 129.0 110.0 01:50:41 INFO - PROCESS | 1660 | 01:50:41 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:185.20 Median:185.00 stddev:1.03 (0.6%) stddev-sans-first:1.09 01:50:41 INFO - PROCESS | 1660 | Values: 185.0 185.0 185.0 187.0 183.0 185.0 185.0 186.0 185.0 186.0 01:50:41 INFO - PROCESS | 1660 | 01:50:41 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:75.10 Median:80.00 stddev:14.09 (17.6%) stddev-sans-first:14.72 01:50:41 INFO - PROCESS | 1660 | Values: 82.0 62.0 58.0 93.0 60.0 63.0 75.0 82.0 98.0 78.0 01:50:41 INFO - PROCESS | 1660 | 01:50:41 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:76.00 stddev:8.37 (11.0%) stddev-sans-first:7.56 01:50:41 INFO - PROCESS | 1660 | Values: 88.0 70.0 87.0 70.0 69.0 68.0 83.0 69.0 69.0 82.0 01:50:41 INFO - PROCESS | 1660 | 01:50:41 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:93.30 Median:89.00 stddev:12.91 (14.5%) stddev-sans-first:13.64 01:50:41 INFO - PROCESS | 1660 | Values: 90.0 89.0 89.0 88.0 89.0 90.0 89.0 130.0 90.0 89.0 01:50:41 INFO - PROCESS | 1660 | 01:50:41 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:105.40 Median:96.00 stddev:17.98 (18.7%) stddev-sans-first:18.04 01:50:41 INFO - PROCESS | 1660 | Values: 122.0 97.0 95.0 96.0 96.0 96.0 97.0 95.0 110.0 150.0 01:50:41 INFO - PROCESS | 1660 | 01:50:41 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:92.10 Median:85.50 stddev:15.14 (17.7%) stddev-sans-first:15.77 01:50:41 INFO - PROCESS | 1660 | Values: 84.0 86.0 85.0 86.0 84.0 85.0 86.0 86.0 110.0 129.0 01:50:41 INFO - PROCESS | 1660 | 01:50:41 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:70.20 Median:64.50 stddev:13.20 (20.5%) stddev-sans-first:13.66 01:50:41 INFO - PROCESS | 1660 | Values: 62.0 62.0 62.0 61.0 65.0 64.0 92.0 96.0 75.0 63.0 01:50:41 INFO - PROCESS | 1660 | 01:50:41 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:3.37 (5.7%) stddev-sans-first:3.24 01:50:41 INFO - PROCESS | 1660 | Values: 63.0 57.0 54.0 66.0 59.0 59.0 57.0 59.0 57.0 59.0 01:50:41 INFO - PROCESS | 1660 | 01:50:41 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:85.00 stddev:7.18 (8.5%) stddev-sans-first:7.42 01:50:41 INFO - PROCESS | 1660 | Values: 85.0 83.0 85.0 85.0 84.0 92.0 99.0 101.0 98.0 84.0 01:50:41 INFO - PROCESS | 1660 | 01:50:41 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:139.50 stddev:20.17 (14.5%) stddev-sans-first:21.07 01:50:41 INFO - PROCESS | 1660 | Values: 137.0 138.0 137.0 136.0 134.0 136.0 141.0 145.0 196.0 170.0 01:50:41 INFO - PROCESS | 1660 | 01:50:41 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.70 Median:164.00 stddev:2.06 (1.3%) stddev-sans-first:2.17 01:50:41 INFO - PROCESS | 1660 | Values: 164.0 164.0 162.0 169.0 164.0 163.0 164.0 164.0 166.0 167.0 01:50:41 INFO - PROCESS | 1660 | 01:50:41 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.80 Median:79.50 stddev:7.87 (9.9%) stddev-sans-first:7.31 01:50:41 INFO - PROCESS | 1660 | Values: 64.0 67.0 70.0 80.0 86.0 80.0 81.0 79.0 77.0 64.0 01:50:41 INFO - PROCESS | 1660 | -------- Summary: end -------- 01:50:41 INFO - PROCESS | 1660 | 01:50:42 INFO - TEST-INFO | 1660: exit 0 01:50:42 INFO - TEST-OK | kraken | took 60646ms 01:50:42 INFO - SUITE-END | took 1181s 01:50:42 INFO - Completed test suite (00:19:42) 01:50:42 INFO - javascript benchmark 01:50:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7529.785130503517, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3404.3531728971493, 3409.369266685663, 3418.1112910643224, 3424.982875085624, 3471.080163756605, 5896.970515147424, 5898.1447690084915, 5907.6750778707155, 5934.406559344069, 5937.435943585352, 5874.588778785486, 5917.615355001922, 5943.910841337383, 5984.401559844014, 5985.311689155748, 5339.4126646069, 5357.571394288448, 5433.4566543345545, 5450.672959622435, 5470.480304371078, 5257.947420525803, 5285.0, 5318.0, 5481.314835645544, 5482.725863706819, 5348.598855085865, 5371.382291036542, 5371.462853714636, 5378.650387724805, 5383.219433182206, 5365.704886231256, 5376.838694839161, 5378.569714422837, 5382.327209098863, 5405.378381486121, 5191.5846732261325, 5316.20256961455, 5318.813841515528, 5338.652987555796, 5356.571474282048, 5903.439173278532, 5905.793297234576, 5912.113183022538, 5914.0, 5916.0238560637445, 6256.468200202989, 6296.905546416808, 6306.211723534559, 6311.747530098791, 6312.842178945517, 6311.779087731908, 6357.300696923335, 6391.201099862517, 6404.519661025395, 6418.390252925965, 5240.816571419982, 6066.302375226861, 6067.666278354689, 6076.210092687922, 6102.603330783485, 6309.116723658692, 6451.387118223808, 6497.512686548528, 6509.674516274167, 6516.1203237562795, 6098.237720284965, 6201.255849298113, 6201.565890387629, 6208.255009398901, 6217.098520714471, 4900.0845254613105, 6301.0, 6313.873722525524, 6354.809355719336, 6372.362763723683, 6374.649394283359, 6376.075469056961, 6376.7130479128555, 6381.010943303795, 6386.233651961795, 6351.333110023381, 6353.777617783356, 6354.555181137369, 6360.459360954278, 6367.872642547216, 4968.30443737877, 5005.149124648746, 5018.0, 5070.797168113234, 5107.080725469377, 4558.886027849264, 4561.863144105682, 4574.222382195035, 4576.839810606612, 4584.381108550303, 5899.557533184942, 5939.70301484924, 5940.138679891478, 5945.583809133319, 5958.106284057426, 6276.592021518587, 6337.96831015842, 6413.45485633717, 6423.100765892778, 6430.678466076677, 5767.250257466587, 5776.624519406225, 5783.479486846204, 5784.942150578525, 5791.247137872049, 5523.337199536081, 5538.6122971391615, 5539.667619942817, 5543.417941116206, 5549.19536667181, 6307.432331090224, 6328.71520781566, 6337.493000559852, 6360.045993101072, 6360.204974378295, 6308.211473565804, 6329.715162817776, 6382.0745991831855, 6382.936170638234, 6385.584936979081, 6526.608403495806, 6534.052562378523, 6537.477001839937, 6538.803835884931, 6555.541112122106, 6368.331325210786, 6370.1718776558755, 6377.17096777416, 6381.202349706286, 6384.744610215539, 6243.656598887105, 6272.435480806749, 6308.14839996613, 6318.557700960889, 6320.462760665211, 6309.463695585743, 6311.084892690443, 6324.272708638381, 6329.082283068838, 6329.683515824282, 6337.144485494399, 6339.778107766206, 6349.460295874717, 6350.555461117678, 6384.840378990562, 5921.289445266423, 5986.790462333797, 6029.60807547508, 6110.43725517484, 6182.412670796267, 6252.124702541557, 6308.52686048539, 6334.588251763621, 6344.921363368331, 6349.365063493614, 5151.459096794782, 6161.045038019114, 6162.383761623981, 6174.598651087665, 6186.443220110212], "value": 5842.651995876141, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.4851603877286, 806.7781360125966, 849.5667209723043, 871.298604623278, 872.6422166911572, 37656.0, 38341.0, 38400.42399364005, 38407.0, 38475.230495390075, 38687.806560967154, 38890.416643750366, 38956.61043389565, 38975.02562435933, 38983.0, 7327.890081648779, 9026.007139214698, 9173.082691730808, 9178.816423671522, 9198.402103863262, 7318.231585683507, 9744.951275243613, 11111.944440277788, 11182.944085279561, 11220.326780393161, 11059.612913548028, 11073.446327683623, 11076.169287303444, 11082.667519974413, 11109.500072496758, 11011.229213954986, 11011.229213954986, 11036.0, 11045.613403530839, 11047.0, 10597.682069537848, 10658.200634952409, 10659.573617055308, 10661.84007239892, 10664.840027399596, 36906.63093369086, 37106.81446592777, 37204.0, 37213.06967325811, 37228.81385593082, 3430.5540279763472, 3777.0934975605655, 3806.71449641278, 3837.319954362688, 3838.328292548793, 24548.386290342707, 26287.948482060696, 27269.045583404484, 27299.044533441232, 27299.590506142424, 2997.640283166012, 3034.347615262729, 3039.863206155728, 3043.5130379139455, 3046.8933587324336, 331642.34178829193, 332038.0, 332535.0, 333563.0, 335184.0, 49911.50088499141, 50048.74878128076, 50125.74937125291, 50183.49816501861, 50249.0, 51243.74378128086, 51479.485205147466, 51518.0, 51823.0, 51886.0, 14193.78709319361, 14313.427462901576, 14329.996900216885, 14337.211453370164, 14349.56951291463, 12562.371881405894, 12562.623121306375, 12573.87426125727, 12574.056945729108, 12576.496940122475, 11032.558697652004, 11560.421978901022, 11644.0, 11702.0, 11703.765924681458, 5518.779248830002, 5658.292713410824, 5674.0, 5680.403557626472, 5683.232763576947, 844.7001314533375, 1096.331237944853, 1099.741560733227, 1125.1673761416494, 1183.408295852074, 20222.696659550118, 20753.481162971046, 24913.50172996566, 24966.502009879103, 25858.83635236419, 802.6468353924255, 850.8723691446332, 1014.2241185493103, 1047.1413440978477, 1146.6158836789602, 13861.306934653227, 14158.362873670709, 14200.928995354958, 14442.422303107758, 14540.927295363666, 423540.0, 425297.0, 426751.0, 427028.0, 427046.0, 43840.0, 53448.465515344345, 53805.1929221062, 53891.730541347046, 53952.730236348565, 1271.5041133957764, 1430.6780974280912, 1460.1093333066822, 1463.0855715177802, 1464.970700585961, 27907.44185116327, 28395.148145555668, 28971.42057158887, 28983.5652465209, 29048.56427153552, 39053.0, 41660.79169604133, 42480.937976550835, 44018.559814401444, 44048.0, 399453.0, 399687.0, 402185.0, 403024.0, 403583.0, 27722.584161238003, 27909.720902791523, 27945.72054279431, 27958.440831182856, 27978.160655180378, 35594.46608300826, 35632.46551301681, 36207.913762587166, 36343.818280908425, 36668.26663466769, 25881.870590646926, 26225.475490490466, 26319.07883224078, 26690.866545667148, 27044.37566553581, 18423.263069477605, 20535.0, 26030.73969260283, 26118.477630447665, 26773.866130669223, 25362.74637253604, 25756.0, 30414.84792576023, 30792.84603576968, 30804.22989425282, 24088.15691450791, 24606.507869842862, 27438.725612744416, 27677.169684909484, 27921.16236512908, 20942.685859712423, 21655.675164872853, 21801.454963626034, 21870.0, 21918.45203869916], "value": 17711.61047144554, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.39877790646614, 409.84016233668876, 419.8005947175092, 424.7451529082551, 447.8902668846133, 10263.281570290073, 14425.495107671233, 20403.795962040374, 20722.481937951557, 21048.263310784125, 21177.258795942143, 21416.892915535438, 22133.66799498009, 22142.88928555355, 22423.551528969412, 6542.214934207902, 6548.934510654892, 6713.496487763413, 6801.387875091241, 6824.761133360334, 4087.2234275487563, 4229.830806767725, 6612.669366531678, 6715.059891615178, 6739.595624262535, 4222.641075508585, 7638.388928885676, 7715.035620547432, 7763.029621297338, 7767.883481747778, 6207.937920620826, 7622.085349758009, 7688.231176882323, 7702.730404435846, 7716.074071111474, 7171.390431813302, 7318.3047610476915, 7415.0, 7437.479376443597, 7443.069616297963, 26009.21972340833, 26156.215313540622, 26171.73828261712, 26269.605955910676, 26351.868240658867, 2876.5828954801436, 2966.110166949928, 2998.8200707957376, 2998.9400211995858, 3002.5287609071397, 16050.678986420324, 16121.113338766363, 16265.512034638861, 16335.9381640193, 16898.0, 1917.8753381030187, 2038.0129373860516, 2071.1301253473575, 2108.704781330615, 2148.785121487854, 101920.0, 103190.22805846402, 112082.0, 124507.3774631121, 128924.7107528922, 19884.50288742793, 20798.27206047781, 20890.686639700416, 20926.37220883376, 21083.578328433345, 33237.50143747846, 33287.83356083204, 33306.66773329088, 33378.83310583432, 33460.83269583636, 10653.84019239712, 10716.410597417216, 10869.565217391373, 10874.130069594572, 10980.396078215615, 7261.491695581364, 8790.516521591246, 8835.425697329652, 8890.822183556293, 9007.414518056305, 9754.290653167058, 10019.398836069859, 10111.898881011242, 10147.955613315975, 10158.39049657023, 4045.37296719009, 4082.7346222495444, 4121.814518346621, 4129.855455059059, 4136.627703506699, 929.4005366538545, 1023.5649848814224, 1031.2832581356035, 1033.0289527843802, 1035.249444152983, 18695.532611684815, 20994.79005209959, 21277.148914043264, 21430.357089287347, 21465.89267053655, 1029.8281293615646, 1048.491481631408, 1060.2419270221828, 1073.382804887193, 1083.9891601084046, 10710.46447677613, 11566.826497602542, 11573.76852462946, 11607.245529040754, 11610.303381797119, 723489.0, 731296.0, 750450.0, 766647.0, 770449.0, 102678.48660756797, 123001.76998229903, 123657.0, 126787.73212267943, 128067.0, 1275.6938334799581, 1315.5395611536078, 1348.447136673959, 1351.8850897673808, 1355.735631551838, 19006.524836878634, 19141.521461963006, 19374.70937935904, 19519.512012199808, 19565.413037608894, 45157.871053223935, 64122.03816942656, 65120.69758604896, 65865.0, 67857.0, 92612.0, 92873.60689589797, 93535.53232233795, 95168.0, 95750.0, 9314.25485961108, 11309.264897781617, 11541.0, 11767.23512971654, 11858.11064170208, 12275.324857132944, 12445.0, 12625.936870315956, 12869.871301286867, 12957.963362931132, 10403.323783954018, 10847.294925830112, 11021.614243501439, 11196.94401527987, 11207.0, 9161.175494205287, 9372.109649583279, 10326.690199294033, 10339.844902326622, 10428.32215905994, 10801.567937282422, 10841.728956775829, 10877.728056798644, 10961.671149865517, 11275.830862537232, 11040.668779936615, 11056.447177640925, 11230.3261804292, 11265.493052812479, 11471.483783229607, 9769.364991275545, 10779.892201078203, 10936.726581835517, 10985.121190304917, 11017.669469915916], "value": 11433.796452847171, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1393.3312010235086, 1544.9691006179883, 1552.200616682408, 1571.3321838218762, 1578.739507981183, 10483.842742358862, 10950.233483656137, 10951.890481095186, 10956.068734157596, 10976.0, 10589.311694739836, 10778.029977302042, 10921.617743378984, 10957.56169753209, 10997.61508347208, 1983.3157560641591, 2358.740538540759, 2359.1035406545575, 2360.492494113765, 2361.763823617633, 2259.378670865517, 2330.1611419888827, 2342.8360014798973, 2349.259983105316, 2351.9176828810996, 2303.9424014399606, 2314.7569505201964, 2330.592146374386, 2344.23812261015, 2352.3178278299274, 2260.5930932432157, 2310.364649721323, 2312.225404489498, 2323.6282194848827, 2339.4619237575284, 2330.976690233093, 2332.043862016582, 2336.100601268524, 2337.9298621041394, 2339.5320935812733, 11016.834747478793, 11080.889191108066, 11092.223544351898, 11094.55621775136, 11100.834412386745, 5474.589405794582, 5651.2935883014625, 5654.095344744862, 5663.9716801415725, 5667.3482549506925, 3255.788373755698, 3353.724001897576, 3518.630543792916, 3521.6126226115107, 3528.153243221634, 1103.2340289947351, 1131.7906187355366, 1153.1293873125737, 1153.3656488931053, 1172.7478592102739, 13341.0, 13776.311184440738, 13935.860641393558, 15082.849171508255, 15136.091834489966, 4379.64962802975, 4525.773711314421, 4543.363929049936, 4544.931826022612, 4581.74800385982, 11508.482118304697, 11511.884881151082, 11523.366214858263, 11537.250078744855, 11538.134639901873, 4713.34013238147, 4902.1911384621135, 5290.9735451322495, 5442.537384322298, 5446.782128714807, 5047.87380315495, 5113.71874546896, 5117.2068329409, 5125.359330083739, 5130.743462826844, 3698.167912219783, 3746.138388170736, 3799.696024318048, 3806.676432503268, 3819.3125237457525, 948.7059011706393, 954.3271993244746, 968.5786682792867, 969.6363863551169, 986.4377304936118, 926.9953650231706, 950.3632566180676, 952.4333021851987, 957.1481381570408, 1005.4570531912685, 6589.86820263592, 6599.670016499252, 6690.431313338421, 6842.965785171043, 6853.314668533107, 878.6178012564554, 1042.6819819954835, 1055.2718624149313, 1085.478970094359, 1096.6929259807266, 4145.834166633301, 4437.889052773707, 4453.153900758845, 4525.117602067565, 4535.795889184995, 13769.518066867611, 14195.503157389649, 14260.35828387725, 14261.001729879017, 14269.500567480089, 12025.4588543514, 13347.799783003262, 14111.576652700436, 14144.36350364236, 14168.716625667636, 9812.215022798158, 10082.899171008195, 10098.747531311776, 10099.293049486612, 10118.645847395306, 5512.862178445571, 6778.8983165251575, 6792.864142717019, 6953.478489113438, 7028.683709233199, 11757.0, 12151.696207594881, 12461.87538124607, 12547.560835370718, 12564.057695672678, 9548.713538593853, 11293.983541481306, 11585.768284634429, 12353.567625133077, 12372.257664540157, 8533.359998000298, 8631.654733810577, 8874.51190184546, 9045.095490450902, 9254.629814807333, 10001.0, 10043.949780251052, 10163.441010744184, 10165.085142337226, 10255.230857685554, 7255.963720181366, 8579.35654825878, 8579.742607721779, 8599.699010534601, 8610.61252243638, 8481.194286542768, 8536.317094632539, 8539.74380768578, 8548.358873084419, 8551.957240213758, 7717.922820771721, 7775.650095745818, 7899.486533375312, 7902.723404680809, 7909.564973926259, 5843.386444423444, 5902.468777810018, 5979.5814292999075, 6039.577229593886, 6040.486558642565, 5850.970745146247, 5941.31674857397, 5978.282606087298, 6057.939420605914, 6159.692015399301], "value": 5042.562555903831, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.44519871020555, 369.25042164406267, 373.57785702156565, 378.92800367930107, 379.66779068315225, 4548.454185497737, 4970.204767237243, 5069.61727987846, 5121.462246464123, 5131.588813076406, 4186.476690413699, 4220.577942205778, 4222.619964203221, 4500.369948207245, 4948.653594248404, 3538.982779815506, 3748.3707621087933, 4018.216447792689, 4132.917341653165, 4183.937240941388, 4024.7182697211206, 4104.97947510262, 4132.814023368941, 4161.375793630965, 4221.915561688765, 4188.0, 4231.830726770925, 4244.299690551071, 4247.25673007224, 4276.2894842063115, 3642.7814331139953, 3732.1789206374556, 3896.9478240875087, 4223.086070611268, 4300.870973870805, 3429.9314013719586, 3849.480320156771, 3857.961420385788, 3870.6709929656013, 3875.6899448044087, 4883.511648835123, 4902.754862256873, 4922.203395760557, 4942.975285123552, 4962.975185124087, 3856.9421458678144, 4564.7032942858605, 4661.645157759649, 4727.598154156901, 4744.264638980963, 4592.7933243004145, 4866.836556238438, 4968.3292755477905, 5047.0, 5234.319538459976, 3833.5591406988274, 4120.835166593332, 4125.339945608688, 4278.893027674302, 4297.269464191095, 5317.680939143664, 5419.160030195326, 5484.669015668325, 5570.888582228374, 5689.687067211302, 3672.137047793766, 3942.8619998300496, 4092.4066010428915, 4388.539203383599, 4407.933880991788, 3927.823247953792, 4480.401367207012, 4500.0, 4524.484297760221, 4543.704659197141, 4152.231837110145, 4813.638977076664, 4820.939393333461, 4845.442774080955, 4869.732164730903, 4510.751908645056, 4744.686218546478, 4941.75291235444, 5088.92366614501, 5269.868253293698, 1279.6161151654467, 1382.5644921849585, 1506.691128318692, 1512.0947606016498, 1521.9086854788527, 1068.8830172469789, 1073.7154654016692, 1076.924615276923, 1126.6620013995769, 1142.6971852459105, 4390.47314322277, 4633.235516139866, 4954.894169977817, 4964.304997300381, 5024.296598476216, 5376.247325374451, 5504.889902201933, 5529.751161197756, 5579.832605021856, 5630.352509461382, 4311.4395128633705, 4405.691601587858, 4474.664400170001, 4887.584555312838, 4964.503549645079, 2459.4835084632427, 2629.4346715456268, 2828.43431313734, 2866.7706583473273, 2902.3324635333993, 5099.745012749348, 5126.436092029874, 5641.633293835969, 5706.229658996065, 5724.8282551523525, 4902.950970490249, 5025.120603894304, 5333.146696528537, 5493.835184944458, 5622.437756224426, 4797.56821886032, 5369.288509302777, 5384.0, 5569.999999999919, 5754.798582049608, 4889.779959901742, 4968.27959945813, 5500.312460942382, 5550.833474995757, 5567.246317410003, 4494.662900282427, 5123.386133367977, 5180.161042829892, 5476.645042631707, 5514.834554963358, 4765.73788441639, 4837.55703369796, 5023.598112151092, 5621.915671264853, 5686.573506987075, 4079.265732168238, 4782.306565548046, 4807.106243942418, 4811.927821082617, 4821.9035619286715, 4465.5087940327185, 5247.9737601311745, 5443.503036664949, 5520.089185284383, 5558.664241891332, 4590.816367345269, 4690.577847993697, 5121.667091639031, 5162.457941916045, 5338.652987555952, 4662.743549104832, 4749.881252968704, 4765.8093676252565, 5313.990341835039, 5314.468553144655, 4493.752843593634, 4546.704464209815, 4671.976640116778, 5008.398992120969, 5264.657797243167, 4604.178531818649, 5290.629655924048, 5339.211364193039, 5340.759665815125, 5356.035913535601, 5228.843134705965, 5237.607179461632, 5259.4477579853565, 5276.314079169683, 5278.604104692086], "value": 4056.5821142480568, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.8253577873422, 735.9668814903332, 768.2010708862783, 815.2458975447711, 827.300930713547, 15318.234088295583, 16123.83876161238, 16128.435504757335, 16133.112678802661, 16141.43504977326, 14762.96659233854, 14853.0, 14903.776443353358, 14921.477748278812, 15257.542273731779, 895.3463971300954, 922.0733163171037, 922.6908985489869, 923.833709932212, 944.7921457279388, 920.1856357123927, 940.8776859008319, 941.4492521874712, 943.3490891285026, 944.3955868244329, 990.2474119669031, 990.5790039233333, 990.9554070066828, 993.8310487217154, 994.0159242350094, 985.4235272365675, 987.9209663226925, 988.4316518002141, 989.1147423056324, 989.8960609136082, 734.6473692627516, 735.1582438108336, 735.2023054985348, 735.8638651849371, 736.690589952216, 15933.283002264925, 16174.75737863933, 16217.0, 16535.007899526066, 16828.74756878648, 13708.314584270747, 13790.793138102936, 13931.0, 13957.79063314041, 14189.929050354785, 11697.532098716041, 11702.473388697445, 11912.821307680391, 12003.099767517471, 12031.518739250507, 3626.4197728363597, 3667.3215455140885, 3674.6325367463305, 3684.9866286771353, 3800.600936901641, 50526.108761923366, 68815.0, 100758.0, 101010.49494752553, 111835.44082279537, 10608.628697995533, 10675.73310667224, 11040.834387484194, 11086.833697494543, 11149.721256968478, 21455.141794328363, 21821.23625673094, 21824.345269641937, 21874.343769686933, 22023.11907523681, 7853.136155022943, 7913.881291780628, 7957.442978991531, 7964.163762804822, 7967.9203207967175, 4276.786160691953, 4320.589543993316, 4336.176126535948, 4360.7601581913295, 4374.0158464345905, 3488.284901595167, 3558.1994051338243, 3570.785752854837, 3588.2105936693883, 3598.1544337081286, 648.8734696734092, 650.4341223135807, 654.1267408010359, 665.5740326191242, 666.5167753378762, 547.5236544206566, 572.2703552969913, 581.855861151649, 588.9941100589025, 603.8611119442465, 10196.643117491001, 10278.17774578032, 10594.57621695139, 10604.363738175734, 10744.247902646895, 682.6245564939263, 683.2347770497075, 685.7702669605638, 709.5884387055495, 713.4399496395305, 4744.146053710365, 4816.903661926812, 5037.370328708911, 5037.974810125926, 5042.243663450512, 100430.49354259692, 104151.47924260482, 104966.95033049725, 105936.0, 106775.0, 75361.0, 76027.0, 83723.74414383789, 84968.0, 87624.56187719021, 63137.737245255754, 71323.0, 73553.0, 74092.62953685196, 75938.0, 8905.198532132139, 9448.102430269113, 9578.760530986781, 9596.99231580674, 9629.711108666752, 41841.79079104707, 43555.782221088695, 47124.52875471202, 48093.7976550589, 51330.0, 58283.70858145682, 62081.06878396737, 69062.0, 69617.30382696108, 71868.0, 12334.259944403364, 12682.682932926751, 13012.0, 13245.403956821774, 13293.800592991307, 13883.583492495241, 14020.859791401956, 14978.925105374403, 15001.0, 15051.247437628293, 13298.335083245993, 13750.793738093736, 14033.29833508341, 14035.228062456663, 14518.056326338754, 11709.941450292694, 13188.0, 13382.330883455983, 13934.163950163023, 14432.85567144315, 12939.870601293867, 13459.865401345862, 13647.795283070564, 14102.153870767786, 14138.717225655224, 9493.0, 10087.646932357333, 10231.641892533726, 10252.0, 10567.947160264457, 10086.243531735003, 10478.685639430829, 10673.785179614451, 11060.391678457761, 11638.418079095842], "value": 7529.805902668143, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1489.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 171.0, 125.0, 123.0, 168.0, 161.0, 117.0, 116.0, 117.0, 116.0], "value": 137.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 130.0, 111.0, 142.0, 111.0, 108.0, 108.0, 108.0, 129.0, 110.0], "value": 120.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 185.0, 187.0, 183.0, 185.0, 185.0, 186.0, 185.0, 186.0], "value": 185.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 62.0, 58.0, 93.0, 60.0, 63.0, 75.0, 82.0, 98.0, 78.0], "value": 75.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 87.0, 70.0, 69.0, 68.0, 83.0, 69.0, 69.0, 82.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 89.0, 88.0, 89.0, 90.0, 89.0, 130.0, 90.0, 89.0], "value": 93.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 97.0, 95.0, 96.0, 96.0, 96.0, 97.0, 95.0, 110.0, 150.0], "value": 105.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 86.0, 84.0, 85.0, 86.0, 86.0, 110.0, 129.0], "value": 92.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 61.0, 65.0, 64.0, 92.0, 96.0, 75.0, 63.0], "value": 70.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 57.0, 54.0, 66.0, 59.0, 59.0, 57.0, 59.0, 57.0, 59.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 85.0, 85.0, 84.0, 92.0, 99.0, 101.0, 98.0, 84.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 138.0, 137.0, 136.0, 134.0, 136.0, 141.0, 145.0, 196.0, 170.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 162.0, 169.0, 164.0, 163.0, 164.0, 164.0, 166.0, 167.0], "value": 164.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 70.0, 80.0, 86.0, 80.0, 81.0, 79.0, 77.0, 64.0], "value": 74.8, "unit": "score"}]}]} 01:50:42 INFO - Return code: 0 01:50:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:50:42 ERROR - # TBPL SUCCESS # 01:50:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:50:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:50:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:50:42 INFO - 2017-05-08 01:50:42.425 screenresolution[1664:11998] starting screenresolution argv=screenresolution get 01:50:42 INFO - 2017-05-08 01:50:42.427 screenresolution[1664:11998] Display 0: 1600x1200x32@60 01:50:42 INFO - 2017-05-08 01:50:42.433 screenresolution[1665:12002] starting screenresolution argv=screenresolution list 01:50:42 INFO - Available Modes on Display 0 01:50:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:50:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:50:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:50:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:50:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:50:42 INFO - Intel Iris: 01:50:42 INFO - Chipset Model: Intel Iris 01:50:42 INFO - Type: GPU 01:50:42 INFO - Bus: Built-In 01:50:42 INFO - VRAM (Dynamic, Max): 1536 MB 01:50:42 INFO - Vendor: Intel (0x8086) 01:50:42 INFO - Device ID: 0x0a2e 01:50:42 INFO - Revision ID: 0x0009 01:50:42 INFO - Displays: 01:50:42 INFO - Display: 01:50:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:50:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:50:42 INFO - Display Serial Number: 1600x1200 60 01:50:42 INFO - Main Display: Yes 01:50:42 INFO - Mirror: Off 01:50:42 INFO - Online: Yes 01:50:42 INFO - Rotation: Supported 01:50:42 INFO - Return code: 0 01:50:42 INFO - Running post-action listener: _resource_record_post_action 01:50:42 INFO - [mozharness: 2017-05-08 08:50:42.681622Z] Finished run-tests step (success) 01:50:42 INFO - Running post-run listener: _resource_record_post_run 01:50:42 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 34044928; Write bytes: 511916032; Read time: 306; Write time: 1813 01:50:42 INFO - TinderboxPrint: CPU usage
53.8% 01:50:42 INFO - TinderboxPrint: I/O read bytes / time
34,044,928 / 306 01:50:42 INFO - TinderboxPrint: I/O write bytes / time
511,916,032 / 1,813 01:50:42 INFO - TinderboxPrint: CPU idle
2,223.7 (46.2%) 01:50:42 INFO - TinderboxPrint: CPU system
956.6 (19.9%) 01:50:42 INFO - TinderboxPrint: CPU user
1,633.4 (33.9%) 01:50:42 INFO - TinderboxPrint: Swap in / out
237,826,048 / 0 01:50:42 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 197149184; Write bytes: 193778688; Read time: 15560; Write time: 398 01:50:42 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 33111040; Write bytes: 298349568; Read time: 293; Write time: 1357 01:50:43 INFO - Running post-run listener: _upload_blobber_files 01:50:43 INFO - Blob upload gear active. 01:50:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:50:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 01:50:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:50:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:50:43 INFO - (blobuploader) - INFO - Open directory for files ... 01:50:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:50:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:50:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:50:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:50:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:50:44 INFO - (blobuploader) - INFO - Done attempting. 01:50:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:50:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:50:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:50:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:50:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:50:45 INFO - (blobuploader) - INFO - Done attempting. 01:50:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:50:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:50:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:50:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:50:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:50:46 INFO - (blobuploader) - INFO - Done attempting. 01:50:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:50:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:50:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:50:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:50:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:50:48 INFO - (blobuploader) - INFO - Done attempting. 01:50:48 INFO - (blobuploader) - INFO - Iteration through files over. 01:50:48 INFO - Return code: 0 01:50:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:50:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:50:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf4b70090ea088c0412948001cc2b2f253028d3d95013541331ec4a9c16573b4af1ad92828d273a359aea41953b81fc04525441fb3cdb71f49b1c64498186ff3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/326edabc5cc680182c3fae687dd80f722bc736d7579adf576f138ee2618075a6db02f39335572715aabe74a9c63299686f8dbef15e36d58cdf03dd6968663102", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bc943d4d92e4564f2fd3bb9dd4a110072fb2a6ca6631af17cb3eb3a7971a807493a8766100ded80ca8eee929a604a16d9baea13ed31087be47adf9012d21ec4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f89b602f33dc617d2d440b5587ed33ff3349e1ef7738bc37238cc8fe70fc7a61ff62a4834c537d565ddfae331e2583e750aef969e77a9a2e9a2f61bd696b39c9"} 01:50:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:50:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:50:48 INFO - Contents: 01:50:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf4b70090ea088c0412948001cc2b2f253028d3d95013541331ec4a9c16573b4af1ad92828d273a359aea41953b81fc04525441fb3cdb71f49b1c64498186ff3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/326edabc5cc680182c3fae687dd80f722bc736d7579adf576f138ee2618075a6db02f39335572715aabe74a9c63299686f8dbef15e36d58cdf03dd6968663102", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bc943d4d92e4564f2fd3bb9dd4a110072fb2a6ca6631af17cb3eb3a7971a807493a8766100ded80ca8eee929a604a16d9baea13ed31087be47adf9012d21ec4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f89b602f33dc617d2d440b5587ed33ff3349e1ef7738bc37238cc8fe70fc7a61ff62a4834c537d565ddfae331e2583e750aef969e77a9a2e9a2f61bd696b39c9"} 01:50:48 INFO - Running post-run listener: copy_logs_to_upload_dir 01:50:48 INFO - Copying logs to upload dir... 01:50:48 INFO - mkdir: /builds/slave/test/build/upload/logs 01:50:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.246221 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-08 01:50:48.448792) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-08 01:50:48.452194) ========= 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.LocHGaiKhf/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.xX9IpPzt2V/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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/cf4b70090ea088c0412948001cc2b2f253028d3d95013541331ec4a9c16573b4af1ad92828d273a359aea41953b81fc04525441fb3cdb71f49b1c64498186ff3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/326edabc5cc680182c3fae687dd80f722bc736d7579adf576f138ee2618075a6db02f39335572715aabe74a9c63299686f8dbef15e36d58cdf03dd6968663102", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bc943d4d92e4564f2fd3bb9dd4a110072fb2a6ca6631af17cb3eb3a7971a807493a8766100ded80ca8eee929a604a16d9baea13ed31087be47adf9012d21ec4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f89b602f33dc617d2d440b5587ed33ff3349e1ef7738bc37238cc8fe70fc7a61ff62a4834c537d565ddfae331e2583e750aef969e77a9a2e9a2f61bd696b39c9"} build_url:https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010828 build_url: 'https://queue.taskcluster.net/v1/task/Dom8N78HRgmfNNmtX9Jjsg/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/cf4b70090ea088c0412948001cc2b2f253028d3d95013541331ec4a9c16573b4af1ad92828d273a359aea41953b81fc04525441fb3cdb71f49b1c64498186ff3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/326edabc5cc680182c3fae687dd80f722bc736d7579adf576f138ee2618075a6db02f39335572715aabe74a9c63299686f8dbef15e36d58cdf03dd6968663102", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bc943d4d92e4564f2fd3bb9dd4a110072fb2a6ca6631af17cb3eb3a7971a807493a8766100ded80ca8eee929a604a16d9baea13ed31087be47adf9012d21ec4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f89b602f33dc617d2d440b5587ed33ff3349e1ef7738bc37238cc8fe70fc7a61ff62a4834c537d565ddfae331e2583e750aef969e77a9a2e9a2f61bd696b39c9"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-08 01:50:48.488826) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 01:50:48.489328) ========= 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.LocHGaiKhf/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.xX9IpPzt2V/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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.005266 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-08 01:50:48.541834) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-08 01:50:48.544945) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-08 01:50:48.545380) ========= ========= Total master_lag: 0.19 =========