builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0014 starttime: 1491427600.31 results: success (0) buildid: 20170405125420 builduid: 261490be90d14e41afac8b3bf8761ef4 revision: 730db8b60c1e4443af45f94b6411b81c889e0759 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-05 14:26:40.312221) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-05 14:26:40.312659) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-05 14:26:40.312939) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-05 14:26:40.339723) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 14:26:40.340412) ========= 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.lkxRJMatoR/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.WlSeK2g2v1/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-05 14:26:40-- 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% 9.79M=0.001s 2017-04-05 14:26:40 (9.79 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091038 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 14:26:40.456443) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 14:26:40.456761) ========= 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.lkxRJMatoR/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.WlSeK2g2v1/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.021548 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 14:26:40.505107) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-05 14:26:40.505510) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 730db8b60c1e4443af45f94b6411b81c889e0759 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 730db8b60c1e4443af45f94b6411b81c889e0759 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lkxRJMatoR/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.WlSeK2g2v1/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-05 14:26:40,590 truncating revision to first 12 chars 2017-04-05 14:26:40,590 Setting DEBUG logging. 2017-04-05 14:26:40,590 attempt 1/10 2017-04-05 14:26:40,590 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/730db8b60c1e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-05 14:26:41,957 unpacking tar archive at: mozilla-central-730db8b60c1e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.634770 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-05 14:26:42.168106) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-05 14:26:42.168502) ========= script_repo_revision: 730db8b60c1e4443af45f94b6411b81c889e0759 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-05 14:26:42.168973) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-05 14:26:42.169303) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-05 14:26:42.188646) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-04-05 14:26:42.189363) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lkxRJMatoR/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.WlSeK2g2v1/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:26:42 INFO - MultiFileLogger online at 20170405 14:26:42 in /builds/slave/test 14:26:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:26:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:26:42 INFO - {'append_to_log': False, 14:26:42 INFO - 'base_work_dir': '/builds/slave/test', 14:26:42 INFO - 'blob_upload_branch': 'Firefox', 14:26:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:26:42 INFO - 'branch': 'Firefox', 14:26:42 INFO - 'buildbot_json_path': 'buildprops.json', 14:26:42 INFO - 'config_files': ('talos/mac_config.py',), 14:26:42 INFO - 'default_actions': ('clobber', 14:26:42 INFO - 'read-buildbot-config', 14:26:42 INFO - 'download-and-extract', 14:26:42 INFO - 'populate-webroot', 14:26:42 INFO - 'create-virtualenv', 14:26:42 INFO - 'install', 14:26:42 INFO - 'run-tests'), 14:26:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:26:42 INFO - 'download_minidump_stackwalk': True, 14:26:42 INFO - 'download_symbols': 'ondemand', 14:26:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:26:42 INFO - 'tooltool.py': '/tools/tooltool.py', 14:26:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:26:42 INFO - '/tools/misc-python/virtualenv.py')}, 14:26:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:26:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:26:42 INFO - 'gecko_profile': False, 14:26:42 INFO - 'gecko_profile_interval': 0, 14:26:42 INFO - 'installer_path': 'installer.exe', 14:26:42 INFO - 'log_level': 'info', 14:26:42 INFO - 'log_name': 'talos', 14:26:42 INFO - 'log_to_console': True, 14:26:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:26:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:26:42 INFO - 'opt_config_files': (), 14:26:42 INFO - 'pip_index': False, 14:26:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:26:42 INFO - 'cmd': ('bash', 14:26:42 INFO - '-c', 14:26:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:26:42 INFO - 'enabled': True, 14:26:42 INFO - 'halt_on_failure': False, 14:26:42 INFO - 'name': 'check_screen_resolution'},), 14:26:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:26:42 INFO - 'cmd': ('bash', 14:26:42 INFO - '-c', 14:26:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:26:42 INFO - 'enabled': True, 14:26:42 INFO - 'halt_on_failure': False, 14:26:42 INFO - 'name': 'check_screen_resolution'},), 14:26:42 INFO - 'run_cmd_checks_enabled': True, 14:26:42 INFO - 'suite': 'dromaeojs', 14:26:42 INFO - 'system_bits': '32', 14:26:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:26:42 INFO - 'title': 't-yosemite-r7-0014', 14:26:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:26:42 INFO - 'use_talos_json': True, 14:26:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:26:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:26:42 INFO - 'work_dir': 'build'} 14:26:42 INFO - [mozharness: 2017-04-05 21:26:42.405483Z] Running clobber step. 14:26:42 INFO - Running pre-action listener: _resource_record_pre_action 14:26:42 INFO - Running main action method: clobber 14:26:42 INFO - rmtree: /builds/slave/test/build 14:26:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:26:44 INFO - Running post-action listener: _resource_record_post_action 14:26:44 INFO - [mozharness: 2017-04-05 21:26:44.442300Z] Finished clobber step (success) 14:26:44 INFO - [mozharness: 2017-04-05 21:26:44.442477Z] Running read-buildbot-config step. 14:26:44 INFO - Running pre-action listener: _resource_record_pre_action 14:26:44 INFO - Running main action method: read_buildbot_config 14:26:44 INFO - Using buildbot properties: 14:26:44 INFO - { 14:26:44 INFO - "project": "", 14:26:44 INFO - "product": "firefox", 14:26:44 INFO - "script_repo_revision": "production", 14:26:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 14:26:44 INFO - "repository": "", 14:26:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 14:26:44 INFO - "buildid": "20170405125420", 14:26:44 INFO - "pgo_build": "False", 14:26:44 INFO - "basedir": "/builds/slave/test", 14:26:44 INFO - "buildnumber": 25, 14:26:44 INFO - "slavename": "t-yosemite-r7-0014", 14:26:44 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 14:26:44 INFO - "platform": "yosemite_r7", 14:26:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:26:44 INFO - "branch": "mozilla-central", 14:26:44 INFO - "repo_path": "mozilla-central", 14:26:44 INFO - "revision": "730db8b60c1e4443af45f94b6411b81c889e0759", 14:26:44 INFO - "stage_platform": "macosx64", 14:26:44 INFO - "builduid": "261490be90d14e41afac8b3bf8761ef4", 14:26:44 INFO - "slavebuilddir": "test" 14:26:44 INFO - } 14:26:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 14:26:44 INFO - Running post-action listener: _resource_record_post_action 14:26:44 INFO - [mozharness: 2017-04-05 21:26:44.445893Z] Finished read-buildbot-config step (success) 14:26:44 INFO - [mozharness: 2017-04-05 21:26:44.446029Z] Running download-and-extract step. 14:26:44 INFO - Running pre-action listener: _resource_record_pre_action 14:26:44 INFO - Running main action method: download_and_extract 14:26:44 INFO - mkdir: /builds/slave/test/build/tests 14:26:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:44 INFO - trying https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:26:44 INFO - Downloading https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:26:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 14:26:45 INFO - Downloaded 1325 bytes. 14:26:45 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:26:45 INFO - Using the following test package requirements: 14:26:45 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:26:45 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:26:45 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:26:45 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:26:45 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:26:45 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:26:45 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:26:45 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:26:45 INFO - u'jsshell-mac.zip'], 14:26:45 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:26:45 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:26:45 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:26:45 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:26:45 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:26:45 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:26:45 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:26:45 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:26:45 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:26:45 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:26:45 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:26:45 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:26:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:26:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:26:45 INFO - Fetch https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:26:47 INFO - Expected file size: 52517711 14:26:47 INFO - Obtained file size: 52517711 14:27:03 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:27:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:27:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:27:03 INFO - Fetch https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:27:05 INFO - Expected file size: 52517711 14:27:05 INFO - Obtained file size: 52517711 14:27:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:27:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:27:20 INFO - Fetch https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:27:21 INFO - Expected file size: 14404152 14:27:21 INFO - Obtained file size: 14404152 14:27: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')]} 14:27:21 INFO - trying https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:27:21 INFO - Downloading https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:27:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 14:27:23 INFO - Downloaded 70746414 bytes. 14:27:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:27:23 INFO - mkdir: /builds/slave/test/properties 14:27:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:27:23 INFO - Writing to file /builds/slave/test/properties/build_url 14:27:23 INFO - Contents: 14:27:23 INFO - build_url:https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:27:24 INFO - Running post-action listener: _resource_record_post_action 14:27:24 INFO - Running post-action listener: set_extra_try_arguments 14:27:24 INFO - [mozharness: 2017-04-05 21:27:24.602566Z] Finished download-and-extract step (success) 14:27:24 INFO - [mozharness: 2017-04-05 21:27:24.602716Z] Running populate-webroot step. 14:27:24 INFO - Running pre-action listener: _resource_record_pre_action 14:27:24 INFO - Running main action method: populate_webroot 14:27:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:27:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:27:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:27:24 INFO - '--tppagecycles', 14:27:24 INFO - '7'], 14:27:24 INFO - 'tests': ['tsvgm']}}, 14:27:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:27:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:27:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:27:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:27:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:27:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:27:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:27:24 INFO - 'talos_options': ['--disable-e10s'], 14:27:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:27:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:27:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:27:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:27:24 INFO - 'talos_options': ['--disable-e10s'], 14:27:24 INFO - 'tests': ['damp', 'tps']}, 14:27:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:27:24 INFO - 'tests': ['damp', 'tps']}, 14:27:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:27:24 INFO - 'tests': ['dromaeo_dom']}, 14:27:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:27:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:27:24 INFO - 'tests': ['basic_compositor_video']}, 14:27:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:27:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:27:24 INFO - 'tests': ['a11yr', 14:27:24 INFO - 'ts_paint', 14:27:24 INFO - 'tpaint', 14:27:24 INFO - 'sessionrestore', 14:27:24 INFO - 'sessionrestore_no_auto_restore', 14:27:24 INFO - 'tabpaint']}, 14:27:24 INFO - 'other-e10s': {'tests': ['a11yr', 14:27:24 INFO - 'ts_paint', 14:27:24 INFO - 'tpaint', 14:27:24 INFO - 'sessionrestore', 14:27:24 INFO - 'sessionrestore_no_auto_restore', 14:27:24 INFO - 'tabpaint']}, 14:27:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:27:24 INFO - 'ts_paint', 14:27:24 INFO - 'tpaint', 14:27:24 INFO - 'sessionrestore', 14:27:24 INFO - 'sessionrestore_no_auto_restore', 14:27:24 INFO - 'tabpaint']}, 14:27:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:27:24 INFO - 'ts_paint', 14:27:24 INFO - 'tpaint', 14:27:24 INFO - 'sessionrestore', 14:27:24 INFO - 'sessionrestore_no_auto_restore', 14:27:24 INFO - 'tabpaint']}, 14:27:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:27:24 INFO - 'tests': ['a11yr', 14:27:24 INFO - 'ts_paint', 14:27:24 INFO - 'tpaint', 14:27:24 INFO - 'sessionrestore', 14:27:24 INFO - 'sessionrestore_no_auto_restore', 14:27:24 INFO - 'tabpaint']}, 14:27:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:27:24 INFO - 'tests': ['a11yr', 14:27:24 INFO - 'ts_paint', 14:27:24 INFO - 'tpaint', 14:27:24 INFO - 'sessionrestore', 14:27:24 INFO - 'sessionrestore_no_auto_restore', 14:27:24 INFO - 'tabpaint']}, 14:27:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:27:24 INFO - 'tests': ['tsvgx', 14:27:24 INFO - 'tsvgr_opacity', 14:27:24 INFO - 'tart', 14:27:24 INFO - 'tscrollx', 14:27:24 INFO - 'cart', 14:27:24 INFO - 'tsvg_static']}, 14:27:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:27:24 INFO - 'tsvgr_opacity', 14:27:24 INFO - 'tart', 14:27:24 INFO - 'tscrollx', 14:27:24 INFO - 'cart', 14:27:24 INFO - 'tsvg_static']}, 14:27:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:27:24 INFO - 'talos_options': ['--disable-e10s'], 14:27:24 INFO - 'tests': ['tp5o']}, 14:27:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:27:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:27:24 INFO - 'talos_options': ['--disable-e10s', 14:27:24 INFO - '--xperf_path', 14:27:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:27:24 INFO - 'tests': ['tp5n']}, 14:27:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:27:24 INFO - 'talos_options': ['--xperf_path', 14:27:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:27:24 INFO - 'tests': ['tp5n']}}, 14:27:24 INFO - 'talos.zip': {'path': '', 14:27:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:27:24 INFO - Running post-action listener: _resource_record_post_action 14:27:24 INFO - [mozharness: 2017-04-05 21:27:24.621030Z] Finished populate-webroot step (success) 14:27:24 INFO - [mozharness: 2017-04-05 21:27:24.621160Z] Running create-virtualenv step. 14:27:24 INFO - Running pre-action listener: _resource_record_pre_action 14:27:24 INFO - Running main action method: create_virtualenv 14:27:24 INFO - Creating virtualenv /builds/slave/test/build/venv 14:27:24 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 14:27:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:27:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:27:24 INFO - Using real prefix '/tools/python27' 14:27:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:27:25 INFO - Installing distribute.............................................................................................................................................................................................done. 14:27:28 INFO - Installing pip.................done. 14:27:28 INFO - Return code: 0 14:27:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:27:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:27:28 INFO - Reading from file tmpfile_stdout 14:27:28 INFO - Output received: 14:27:28 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) 14:27:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:27:28 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')]} 14:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:28 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')]} 14:27:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:28 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 0x10622db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106230768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd2a49c8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10600bd30>, '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 0x105f32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106000be8>, '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.lkxRJMatoR/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.WlSeK2g2v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:27:28 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 14:27:28 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 14:27:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkxRJMatoR/Render', 14:27:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:28 INFO - 'HOME': '/Users/cltbld', 14:27:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:28 INFO - 'LOGNAME': 'cltbld', 14:27:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:28 INFO - 'MOZ_NO_REMOTE': '1', 14:27:28 INFO - 'NO_EM_RESTART': '1', 14:27:28 INFO - 'PAGER': '/bin/cat', 14:27:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:28 INFO - 'PWD': '/builds/slave/test', 14:27:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:28 INFO - 'SHELL': '/bin/bash', 14:27:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WlSeK2g2v1/Listeners', 14:27:28 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:27:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:28 INFO - 'USER': 'cltbld', 14:27:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:28 INFO - 'XPC_FLAGS': '0x0', 14:27:28 INFO - 'XPC_SERVICE_NAME': '0', 14:27:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:28 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 14:27:28 INFO - Cleaning up... 14:27:28 INFO - Return code: 0 14:27:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:27:28 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')]} 14:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:28 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')]} 14:27:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:28 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 0x10622db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106230768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd2a49c8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10600bd30>, '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 0x105f32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106000be8>, '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.lkxRJMatoR/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.WlSeK2g2v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:27:28 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 14:27:28 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 14:27:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkxRJMatoR/Render', 14:27:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:28 INFO - 'HOME': '/Users/cltbld', 14:27:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:28 INFO - 'LOGNAME': 'cltbld', 14:27:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:28 INFO - 'MOZ_NO_REMOTE': '1', 14:27:28 INFO - 'NO_EM_RESTART': '1', 14:27:28 INFO - 'PAGER': '/bin/cat', 14:27:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:28 INFO - 'PWD': '/builds/slave/test', 14:27:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:28 INFO - 'SHELL': '/bin/bash', 14:27:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WlSeK2g2v1/Listeners', 14:27:28 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:27:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:28 INFO - 'USER': 'cltbld', 14:27:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:28 INFO - 'XPC_FLAGS': '0x0', 14:27:28 INFO - 'XPC_SERVICE_NAME': '0', 14:27:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:29 INFO - Downloading/unpacking psutil>=3.1.1 14:27:29 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 14:27:29 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 14:27:29 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 14:27:29 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 14:27:29 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 14:27:29 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 14:27:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:27:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:27:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:27:32 INFO - Installing collected packages: psutil 14:27:32 INFO - Running setup.py install for psutil 14:27:32 INFO - building 'psutil._psutil_osx' extension 14:27:32 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 14:27:33 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 14:27:33 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 14:27:33 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 14:27:33 INFO - building 'psutil._psutil_posix' extension 14:27:33 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 14:27:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:27:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:27:33 INFO - ^ 14:27:33 INFO - 1 warning generated. 14:27:33 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 14:27:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:27:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:27:33 INFO - Successfully installed psutil 14:27:33 INFO - Cleaning up... 14:27:33 INFO - Return code: 0 14:27:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:27: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')]} 14:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27: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')]} 14:27:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10622db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106230768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd2a49c8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10600bd30>, '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 0x105f32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106000be8>, '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.lkxRJMatoR/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.WlSeK2g2v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:27:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:27:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:27:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkxRJMatoR/Render', 14:27:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:33 INFO - 'HOME': '/Users/cltbld', 14:27:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:33 INFO - 'LOGNAME': 'cltbld', 14:27:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:33 INFO - 'MOZ_NO_REMOTE': '1', 14:27:33 INFO - 'NO_EM_RESTART': '1', 14:27:33 INFO - 'PAGER': '/bin/cat', 14:27:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:33 INFO - 'PWD': '/builds/slave/test', 14:27:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:33 INFO - 'SHELL': '/bin/bash', 14:27:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WlSeK2g2v1/Listeners', 14:27:33 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:27:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:33 INFO - 'USER': 'cltbld', 14:27:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:33 INFO - 'XPC_FLAGS': '0x0', 14:27:33 INFO - 'XPC_SERVICE_NAME': '0', 14:27:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:27:33 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 14:27:33 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 14:27:33 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 14:27:33 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 14:27:33 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 14:27:33 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 14:27:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:27:36 INFO - Installing collected packages: mozsystemmonitor 14:27:36 INFO - Running setup.py install for mozsystemmonitor 14:27:36 INFO - Successfully installed mozsystemmonitor 14:27:36 INFO - Cleaning up... 14:27:36 INFO - Return code: 0 14:27:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:27:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:37 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 0x10622db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106230768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd2a49c8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10600bd30>, '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 0x105f32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106000be8>, '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.lkxRJMatoR/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.WlSeK2g2v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:27:37 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 14:27:37 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 14:27:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkxRJMatoR/Render', 14:27:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:37 INFO - 'HOME': '/Users/cltbld', 14:27:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:37 INFO - 'LOGNAME': 'cltbld', 14:27:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:37 INFO - 'MOZ_NO_REMOTE': '1', 14:27:37 INFO - 'NO_EM_RESTART': '1', 14:27:37 INFO - 'PAGER': '/bin/cat', 14:27:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:37 INFO - 'PWD': '/builds/slave/test', 14:27:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:37 INFO - 'SHELL': '/bin/bash', 14:27:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WlSeK2g2v1/Listeners', 14:27:37 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:27:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:37 INFO - 'USER': 'cltbld', 14:27:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:37 INFO - 'XPC_FLAGS': '0x0', 14:27:37 INFO - 'XPC_SERVICE_NAME': '0', 14:27:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:38 INFO - Downloading/unpacking jsonschema==2.5.1 14:27:38 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 14:27:38 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 14:27:38 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 14:27:38 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 14:27:38 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 14:27:38 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 14:27:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:27:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:27:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:27:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:27:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:27:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:27:41 INFO - Downloading functools32-3.2.3-2.tar.gz 14:27:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:27:41 INFO - warning: no files found matching '*.txt' 14:27:41 INFO - no previously-included directories found matching 'build' 14:27:41 INFO - no previously-included directories found matching 'dist' 14:27:41 INFO - no previously-included directories found matching '.git*' 14:27:41 INFO - Installing collected packages: jsonschema, functools32 14:27:41 INFO - Running setup.py install for functools32 14:27:41 INFO - warning: no files found matching '*.txt' 14:27:41 INFO - no previously-included directories found matching 'build' 14:27:41 INFO - no previously-included directories found matching 'dist' 14:27:41 INFO - no previously-included directories found matching '.git*' 14:27:41 INFO - Successfully installed jsonschema functools32 14:27:41 INFO - Cleaning up... 14:27:41 INFO - Return code: 0 14:27:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:27:41 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')]} 14:27:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:41 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')]} 14:27:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:41 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 0x10622db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106230768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd2a49c8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10600bd30>, '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 0x105f32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106000be8>, '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.lkxRJMatoR/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.WlSeK2g2v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:27:41 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 14:27:41 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 14:27:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkxRJMatoR/Render', 14:27:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:41 INFO - 'HOME': '/Users/cltbld', 14:27:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:41 INFO - 'LOGNAME': 'cltbld', 14:27:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:41 INFO - 'MOZ_NO_REMOTE': '1', 14:27:41 INFO - 'NO_EM_RESTART': '1', 14:27:41 INFO - 'PAGER': '/bin/cat', 14:27:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:41 INFO - 'PWD': '/builds/slave/test', 14:27:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:41 INFO - 'SHELL': '/bin/bash', 14:27:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WlSeK2g2v1/Listeners', 14:27:41 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:27:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:41 INFO - 'USER': 'cltbld', 14:27:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:41 INFO - 'XPC_FLAGS': '0x0', 14:27:41 INFO - 'XPC_SERVICE_NAME': '0', 14:27:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:27:41 INFO - Cleaning up... 14:27:41 INFO - Return code: 0 14:27:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:27:41 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')]} 14:27:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:41 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')]} 14:27:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:41 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 0x10622db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106230768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd2a49c8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10600bd30>, '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 0x105f32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106000be8>, '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.lkxRJMatoR/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.WlSeK2g2v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:27:41 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 14:27:41 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 14:27:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkxRJMatoR/Render', 14:27:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:41 INFO - 'HOME': '/Users/cltbld', 14:27:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:41 INFO - 'LOGNAME': 'cltbld', 14:27:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:41 INFO - 'MOZ_NO_REMOTE': '1', 14:27:41 INFO - 'NO_EM_RESTART': '1', 14:27:41 INFO - 'PAGER': '/bin/cat', 14:27:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:41 INFO - 'PWD': '/builds/slave/test', 14:27:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:41 INFO - 'SHELL': '/bin/bash', 14:27:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WlSeK2g2v1/Listeners', 14:27:41 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:27:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:41 INFO - 'USER': 'cltbld', 14:27:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:41 INFO - 'XPC_FLAGS': '0x0', 14:27:41 INFO - 'XPC_SERVICE_NAME': '0', 14:27:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:41 INFO - Downloading/unpacking blobuploader==1.2.4 14:27:41 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 14:27:41 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 14:27:41 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 14:27:41 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 14:27:41 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 14:27:41 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 14:27:44 INFO - Downloading blobuploader-1.2.4.tar.gz 14:27:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:27:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:27:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:27:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:27:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:27:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:27:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:27:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:27:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:27:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:27:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:27:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:27:45 INFO - Downloading docopt-0.6.1.tar.gz 14:27:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:27:45 INFO - Installing collected packages: blobuploader, requests, docopt 14:27:45 INFO - Running setup.py install for blobuploader 14:27:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:27:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:27:46 INFO - Running setup.py install for requests 14:27:46 INFO - Running setup.py install for docopt 14:27:46 INFO - Successfully installed blobuploader requests docopt 14:27:46 INFO - Cleaning up... 14:27:46 INFO - Return code: 0 14:27:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:27:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:46 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 0x10622db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106230768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd2a49c8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10600bd30>, '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 0x105f32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106000be8>, '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.lkxRJMatoR/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.WlSeK2g2v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:27:46 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 14:27:46 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 14:27:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkxRJMatoR/Render', 14:27:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:46 INFO - 'HOME': '/Users/cltbld', 14:27:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:46 INFO - 'LOGNAME': 'cltbld', 14:27:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:46 INFO - 'MOZ_NO_REMOTE': '1', 14:27:46 INFO - 'NO_EM_RESTART': '1', 14:27:46 INFO - 'PAGER': '/bin/cat', 14:27:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:46 INFO - 'PWD': '/builds/slave/test', 14:27:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:46 INFO - 'SHELL': '/bin/bash', 14:27:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WlSeK2g2v1/Listeners', 14:27:46 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:27:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:46 INFO - 'USER': 'cltbld', 14:27:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:46 INFO - 'XPC_FLAGS': '0x0', 14:27:46 INFO - 'XPC_SERVICE_NAME': '0', 14:27:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:27:47 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-1P524t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:27:47 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-TIgL9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:27:47 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-IABXcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:27:47 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-zwSrBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:27:47 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-IKD0Lr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:27:47 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-wV3t8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:27:47 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-GbOYAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:27:47 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-BKvQk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:27:47 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-ihBchR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:27:47 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-z50HBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:27:48 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-3TK6IB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:27:48 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-2WnYfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:27:48 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-HnJnxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:27:48 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-_NGTz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:27:48 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-dQNAKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:27:48 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-RMPUYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:27:48 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-lFSpUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:27:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:27:48 INFO - Running setup.py install for manifestparser 14:27:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:27:48 INFO - Running setup.py install for mozcrash 14:27:49 INFO - Running setup.py install for mozdebug 14:27:49 INFO - Running setup.py install for mozdevice 14:27:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:27:49 INFO - Running setup.py install for mozfile 14:27:49 INFO - Running setup.py install for mozhttpd 14:27:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:27:49 INFO - Running setup.py install for mozinfo 14:27:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:27:49 INFO - Running setup.py install for mozInstall 14:27:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:27:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:27:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:27:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:27:50 INFO - Running setup.py install for mozleak 14:27:50 INFO - Running setup.py install for mozlog 14:27:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:27:50 INFO - Running setup.py install for moznetwork 14:27:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:27:50 INFO - Running setup.py install for mozprocess 14:27:50 INFO - Running setup.py install for mozprofile 14:27:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:27:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:27:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:27:50 INFO - Running setup.py install for mozrunner 14:27:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:27:50 INFO - Running setup.py install for mozscreenshot 14:27:51 INFO - Running setup.py install for moztest 14:27:51 INFO - Running setup.py install for mozversion 14:27:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:27:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:27:51 INFO - Cleaning up... 14:27:51 INFO - Return code: 0 14:27:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:27:51 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')]} 14:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:51 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')]} 14:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:51 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 0x10622db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106230768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd2a49c8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10600bd30>, '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 0x105f32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106000be8>, '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.lkxRJMatoR/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.WlSeK2g2v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:27:51 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 14:27:51 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 14:27:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkxRJMatoR/Render', 14:27:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:51 INFO - 'HOME': '/Users/cltbld', 14:27:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:51 INFO - 'LOGNAME': 'cltbld', 14:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:51 INFO - 'MOZ_NO_REMOTE': '1', 14:27:51 INFO - 'NO_EM_RESTART': '1', 14:27:51 INFO - 'PAGER': '/bin/cat', 14:27:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:51 INFO - 'PWD': '/builds/slave/test', 14:27:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:51 INFO - 'SHELL': '/bin/bash', 14:27:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WlSeK2g2v1/Listeners', 14:27:51 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:27:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:51 INFO - 'USER': 'cltbld', 14:27:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:51 INFO - 'XPC_FLAGS': '0x0', 14:27:51 INFO - 'XPC_SERVICE_NAME': '0', 14:27:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:27:51 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-ZjWqD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:27:51 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)) 14:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:27:51 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-P_zgM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:27:51 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)) 14:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:27:51 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-wNMVhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:27:52 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)) 14:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:27:52 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-oRTUk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:27:52 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)) 14:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:27:52 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-4cUNs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:27:52 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)) 14:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:27:52 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-U6Vw4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:27:52 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)) 14:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:27:52 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-oNLtzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:27:52 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)) 14:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:27:52 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-d3cB6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:27:52 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)) 14:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:27:52 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-yqL6Jp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:27:52 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)) 14:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:27:52 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-e564_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:27:52 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)) 14:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:27:52 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-7dZXpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:27:52 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)) 14:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:27:52 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-4yCWoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:27:53 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)) 14:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:27:53 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-0FqHWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:27:53 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)) 14:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:27:53 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-1IDVWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:27:53 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)) 14:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:27:53 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-UyElsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:27:53 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)) 14:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:27:53 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-9w3M50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:27:53 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)) 14:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:27:53 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-r7xMPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:27:53 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)) 14:27:53 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)) 14:27:53 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)) 14:27:53 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)) 14:27:53 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)) 14:27:53 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)) 14:27:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:27:53 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 14:27:53 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 14:27:53 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 14:27:53 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 14:27:53 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 14:27:53 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 14:27:56 INFO - Downloading blessings-1.6.tar.gz 14:27:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:27:56 INFO - Installing collected packages: blessings 14:27:56 INFO - Running setup.py install for blessings 14:27:56 INFO - Successfully installed blessings 14:27:56 INFO - Cleaning up... 14:27:56 INFO - Return code: 0 14:27:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:27:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:27:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:27:56 INFO - Reading from file tmpfile_stdout 14:27:56 INFO - Current package versions: 14:27:56 INFO - blessings == 1.6 14:27:56 INFO - blobuploader == 1.2.4 14:27:56 INFO - docopt == 0.6.1 14:27:56 INFO - functools32 == 3.2.3-2 14:27:56 INFO - jsonschema == 2.5.1 14:27:56 INFO - manifestparser == 1.1 14:27:56 INFO - mozInstall == 1.12 14:27:56 INFO - mozcrash == 1.0 14:27:56 INFO - mozdebug == 0.1 14:27:56 INFO - mozdevice == 0.49 14:27:56 INFO - mozfile == 1.2 14:27:56 INFO - mozhttpd == 0.7 14:27:56 INFO - mozinfo == 0.9 14:27:56 INFO - mozleak == 0.1 14:27:56 INFO - mozlog == 3.4 14:27:56 INFO - moznetwork == 0.27 14:27:56 INFO - mozprocess == 0.25 14:27:56 INFO - mozprofile == 0.28 14:27:56 INFO - mozrunner == 6.13 14:27:56 INFO - mozscreenshot == 0.1 14:27:56 INFO - mozsystemmonitor == 0.3 14:27:56 INFO - moztest == 0.8 14:27:56 INFO - mozversion == 1.4 14:27:56 INFO - psutil == 3.1.1 14:27:56 INFO - requests == 1.2.3 14:27:56 INFO - wsgiref == 0.1.2 14:27:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:27:56 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')]} 14:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:56 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')]} 14:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:56 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 0x10622db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106230768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd2a49c8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10600bd30>, '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 0x105f32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106000be8>, '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.lkxRJMatoR/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.WlSeK2g2v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:27:56 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 14:27:56 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 14:27:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkxRJMatoR/Render', 14:27:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:56 INFO - 'HOME': '/Users/cltbld', 14:27:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:56 INFO - 'LOGNAME': 'cltbld', 14:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:56 INFO - 'MOZ_NO_REMOTE': '1', 14:27:56 INFO - 'NO_EM_RESTART': '1', 14:27:56 INFO - 'PAGER': '/bin/cat', 14:27:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:56 INFO - 'PWD': '/builds/slave/test', 14:27:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:56 INFO - 'SHELL': '/bin/bash', 14:27:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WlSeK2g2v1/Listeners', 14:27:56 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:27:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:56 INFO - 'USER': 'cltbld', 14:27:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:56 INFO - 'XPC_FLAGS': '0x0', 14:27:56 INFO - 'XPC_SERVICE_NAME': '0', 14:27:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:57 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)) 14:27:57 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)) 14:27:57 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)) 14:27:57 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)) 14:27:57 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)) 14:27:57 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)) 14:27:57 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)) 14:27:57 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)) 14:27:57 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)) 14:27:57 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)) 14:27:57 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)) 14:27:57 INFO - Cleaning up... 14:27:57 INFO - Return code: 0 14:27:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:27:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:27:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:27:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:27:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10622db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106230768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd2a49c8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10600bd30>, '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 0x105f32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106000be8>, '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.lkxRJMatoR/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.WlSeK2g2v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:27:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:27:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkxRJMatoR/Render', 14:27:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:27:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:27:57 INFO - 'HOME': '/Users/cltbld', 14:27:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:27:57 INFO - 'LOGNAME': 'cltbld', 14:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:27:57 INFO - 'MOZ_NO_REMOTE': '1', 14:27:57 INFO - 'NO_EM_RESTART': '1', 14:27:57 INFO - 'PAGER': '/bin/cat', 14:27:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:27:57 INFO - 'PWD': '/builds/slave/test', 14:27:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:27:57 INFO - 'SHELL': '/bin/bash', 14:27:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WlSeK2g2v1/Listeners', 14:27:57 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:27:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:27:57 INFO - 'USER': 'cltbld', 14:27:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:27:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:27:57 INFO - 'XPC_FLAGS': '0x0', 14:27:57 INFO - 'XPC_SERVICE_NAME': '0', 14:27:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:27:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:27:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:27:57 INFO - Cleaning up... 14:27:57 INFO - Return code: 0 14:27:57 INFO - Running post-action listener: _resource_record_post_action 14:27:57 INFO - Running post-action listener: _start_resource_monitoring 14:27:57 INFO - Starting resource monitoring. 14:27:57 INFO - [mozharness: 2017-04-05 21:27:57.437636Z] Finished create-virtualenv step (success) 14:27:57 INFO - [mozharness: 2017-04-05 21:27:57.438683Z] Running install step. 14:27:57 INFO - Running pre-action listener: _resource_record_pre_action 14:27:57 INFO - Running main action method: install 14:27:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:27:57 INFO - Reading from file tmpfile_stdout 14:27:57 INFO - Detecting whether we're running mozinstall >=1.0... 14:27:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:27:57 INFO - Reading from file tmpfile_stdout 14:27:57 INFO - Output received: 14:27:57 INFO - Usage: mozinstall [options] installer 14:27:57 INFO - Options: 14:27:57 INFO - -h, --help show this help message and exit 14:27:57 INFO - -d DEST, --destination=DEST 14:27:57 INFO - Directory to install application into. [default: 14:27:57 INFO - "/builds/slave/test"] 14:27:57 INFO - --app=APP Application being installed. [default: firefox] 14:27:57 INFO - mkdir: /builds/slave/test/build/application 14:27:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:28:17 INFO - Reading from file tmpfile_stdout 14:28:17 INFO - Output received: 14:28:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:28:17 INFO - Running post-action listener: _resource_record_post_action 14:28:17 INFO - [mozharness: 2017-04-05 21:28:17.501680Z] Finished install step (success) 14:28:17 INFO - [mozharness: 2017-04-05 21:28:17.501795Z] Running run-tests step. 14:28:17 INFO - Running pre-action listener: _resource_record_pre_action 14:28:17 INFO - Running main action method: run_tests 14:28:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:28:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:28:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:28:17 INFO - 2017-04-05 14:28:17.514 screenresolution[1967:19032] starting screenresolution argv=screenresolution get 14:28:17 INFO - 2017-04-05 14:28:17.517 screenresolution[1967:19032] Display 0: 1600x1200x32@60 14:28:17 INFO - 2017-04-05 14:28:17.522 screenresolution[1968:19035] starting screenresolution argv=screenresolution list 14:28:17 INFO - Available Modes on Display 0 14:28:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:28:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:28:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:28:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:28:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:28:17 INFO - Intel Iris: 14:28:17 INFO - Chipset Model: Intel Iris 14:28:17 INFO - Type: GPU 14:28:17 INFO - Bus: Built-In 14:28:17 INFO - VRAM (Dynamic, Max): 1536 MB 14:28:17 INFO - Vendor: Intel (0x8086) 14:28:17 INFO - Device ID: 0x0a2e 14:28:17 INFO - Revision ID: 0x0009 14:28:17 INFO - Displays: 14:28:17 INFO - Display: 14:28:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:28:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:28:17 INFO - Display Serial Number: 1600x1200 60 14:28:17 INFO - Main Display: Yes 14:28:17 INFO - Mirror: Off 14:28:17 INFO - Online: Yes 14:28:17 INFO - Rotation: Supported 14:28:17 INFO - Return code: 0 14:28:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:28:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:28:17 INFO - Python 2.7.3 14:28:17 INFO - Return code: 0 14:28:17 INFO - grabbing minidump binary from tooltool 14:28: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')]} 14:28:17 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 0x10600bd30>, '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 0x105f32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106000be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:28:17 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 14:28:17 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 14:28:17 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 14:28:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:28:17 INFO - Return code: 0 14:28:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:28:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:28:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:28:17 INFO - ENV: RUST_BACKTRACE is now 1 14:28:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:28:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:28:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--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 14:28:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0014 --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 14:28:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lkxRJMatoR/Render', 14:28:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:17 INFO - 'HOME': '/Users/cltbld', 14:28:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:17 INFO - 'LOGNAME': 'cltbld', 14:28:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:28:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:28:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:17 INFO - 'MOZ_NO_REMOTE': '1', 14:28:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:28:17 INFO - 'NO_EM_RESTART': '1', 14:28:17 INFO - 'PAGER': '/bin/cat', 14:28:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:17 INFO - 'PWD': '/builds/slave/test', 14:28:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:28:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:17 INFO - 'RUST_BACKTRACE': '1', 14:28:17 INFO - 'SHELL': '/bin/bash', 14:28:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WlSeK2g2v1/Listeners', 14:28:17 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:28:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:17 INFO - 'USER': 'cltbld', 14:28:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:17 INFO - 'XPC_FLAGS': '0x0', 14:28:17 INFO - 'XPC_SERVICE_NAME': '0', 14:28:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--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 14:28:18 INFO - mozversion application_buildid: 20170405125420 14:28:18 INFO - mozversion application_changeset: 730db8b60c1e4443af45f94b6411b81c889e0759 14:28:18 INFO - mozversion application_display_name: Nightly 14:28:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:28:18 INFO - mozversion application_name: Firefox 14:28:18 INFO - mozversion application_remotingname: firefox 14:28:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:28:18 INFO - mozversion application_vendor: Mozilla 14:28:18 INFO - mozversion application_version: 55.0a1 14:28:18 INFO - mozversion platform_buildid: 20170405125420 14:28:18 INFO - mozversion platform_changeset: 730db8b60c1e4443af45f94b6411b81c889e0759 14:28:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:28:18 INFO - mozversion platform_version: 55.0a1 14:28:18 INFO - using testdate: 1491427698 14:28:18 INFO - actual date: 1491427698 14:28:18 INFO - starting webserver on 'localhost:54348' 14:28:18 INFO - SUITE-START | Running 2 tests 14:28:18 INFO - TEST-START | dromaeo_css 14:28:18 INFO - operating with platform_type : mac_ 14:28:18 INFO - Initialising browser for dromaeo_css test... 14:28:18 INFO - TEST-INFO | started process 1978 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmp_NMDT7/profile http://localhost:54348/getInfo.html) 14:28:20 INFO - PID 1978 | __metrics Screen width/height:1600/1200 14:28:20 INFO - PID 1978 | colorDepth:24 14:28:20 INFO - PID 1978 | Browser inner width/height: 1024/689 14:28:20 INFO - PID 1978 | __metrics 14:28:21 INFO - PID 1978 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:28:21 INFO - PID 1978 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:28:22 INFO - TEST-INFO | 1978: exit 0 14:28:22 INFO - Browser initialized. 14:28:22 INFO - Running cycle 1/1 for dromaeo_css test... 14:28:22 INFO - TEST-INFO | started process 1980 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmp_NMDT7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:28:34 INFO - PID 1980 | RSS: Main: 239722496 14:28:34 INFO - PID 1980 | 14:31:40 INFO - PID 1980 | Cycle 1(1): loaded http://localhost:54348/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54348/tests/dromaeo/cssquery-ext.html) 14:31:42 INFO - PID 1980 | RSS: Main: 426016768 14:31:42 INFO - PID 1980 | 14:33:23 INFO - PID 1980 | ************************* 14:33:23 INFO - PID 1980 | A coding exception was thrown and uncaught in a Task. 14:33:23 INFO - PID 1980 | 14:33:23 ERROR - PID 1980 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:33:23 INFO - PID 1980 | Full stack: 14:33:23 INFO - PID 1980 | ************************* 14:33:23 INFO - PID 1980 | ************************* 14:33:23 INFO - PID 1980 | A coding exception was thrown and uncaught in a Task. 14:33:23 INFO - PID 1980 | 14:33:23 ERROR - PID 1980 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:33:23 INFO - PID 1980 | Full stack: 14:33:23 INFO - PID 1980 | ************************* 14:33:23 INFO - PID 1980 | ************************* 14:33:23 INFO - PID 1980 | A coding exception was thrown and uncaught in a Task. 14:33:23 INFO - PID 1980 | 14:33:23 ERROR - PID 1980 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:33:23 INFO - PID 1980 | Full stack: 14:33:23 INFO - PID 1980 | ************************* 14:33:23 INFO - PID 1980 | ************************* 14:33:23 INFO - PID 1980 | A coding exception was thrown and uncaught in a Task. 14:33:23 INFO - PID 1980 | 14:33:23 ERROR - PID 1980 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:33:23 INFO - PID 1980 | Full stack: 14:33:23 INFO - PID 1980 | ************************* 14:34:44 INFO - PID 1980 | Cycle 1(1): loaded http://localhost:54348/tests/dromaeo/cssquery-ext.html (next: http://localhost:54348/tests/dromaeo/cssquery-jquery.html) 14:34:45 INFO - PID 1980 | RSS: Main: 366817280 14:34:45 INFO - PID 1980 | 14:37:47 INFO - PID 1980 | Cycle 1(1): loaded http://localhost:54348/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54348/tests/dromaeo/cssquery-mootools.html) 14:37:48 INFO - PID 1980 | RSS: Main: 352813056 14:37:48 INFO - PID 1980 | 14:40:50 INFO - PID 1980 | Cycle 1(1): loaded http://localhost:54348/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54348/tests/dromaeo/cssquery-prototype.html) 14:40:51 INFO - PID 1980 | RSS: Main: 356433920 14:40:51 INFO - PID 1980 | 14:43:54 INFO - PID 1980 | Cycle 1(1): loaded http://localhost:54348/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54348/tests/dromaeo/cssquery-yui.html) 14:43:55 INFO - PID 1980 | RSS: Main: 339382272 14:43:55 INFO - PID 1980 | 14:46:58 INFO - PID 1980 | Cycle 1(1): loaded http://localhost:54348/tests/dromaeo/cssquery-yui.html (next: http://localhost:54348/tests/dromaeo/cssquery-dojo.html) 14:46:59 INFO - PID 1980 | RSS: Main: 425574400 14:46:59 INFO - PID 1980 | 14:46:59 INFO - PID 1980 | __start_tp_report 14:46:59 INFO - PID 1980 | _x_x_mozilla_page_load 14:46:59 INFO - PID 1980 | _x_x_mozilla_page_load_details 14:46:59 INFO - PID 1980 | |i|pagename|runs| 14:46:59 INFO - PID 1980 | |0;dojo.html;3460.238747475555;3461.100113970367;3479.4085005549086;3493.755437119401;3533.205028868505;6096.073160812939;6167;6179.412955769206;6205.534584906133;6207.286162091361;5020.76991137171;5709.400512946143;6005.219321488212;6130.2950160731525;6164.660943648098;5297.496737809921;5313.176457649051;5327.840164795043;5336.519713225809;5474.808381706641;5232.502912223333;5333.093374126388;5419.403865574804;5427.83716488506;5469.699166545838;5313.521783039545;5341.813036543741;5342.652727572696;5345.946540534584;5347.358317001946;4408.691391602589;5319.335083114611;5324.733763311819;5325.4142044375085;5344.064788662007;5221.399539052982;5270.657407268515;5278.498542638482;5284.762185701614;5287.365516138049;6011.549133814981;6031;6041.24484439445;6043.335233124353;6049.637021778707;5393.487618676219;6327.430531252164;6389.7763578275;6391.169148010729;6395.8401039973905;6457.2896981332015;6565.409113179837;6661.467082633378;6665.533412661116;6686.063951046857;6294.590851594678;6295.5907865988565;6296.905546416808;6305.8108256752375;6317.4630156437215;6496.935030649727;6755.729770809211;6779.966100169468;6810.829729256809;6834.453243740489;6335.239771227482;6371.39471750183;6397.200349956255;6436.7425302988295;6476.740930362733;6612.107365505595;6614.338566143347;6619.5035372347265;6619.801405957829;6633.4693224541925;6486.35136486357;6495.0582165586675;6507.739690412425;6516.934830651728;6526.771562995273;6278.591891527036;6373.426391624776;6373.585716928477;6412.67936603168;6495.090687303781;5260.631755777059;5338.866528336823;5341.359036915517;5345.786168553292;5352.437994010573;4684.953150468452;4691.3432119503295;4695.413073365829;4699.741514216683;4727.527247275314;6192.411720886508;6221;6274.964630835952;6290.622562646256;6291.748330066837;6420.7431702731365;6436.3241859605005;6437.871242575122;6464.935350646528;6468.482521398277;6166.630002199882;6166.81499555014;6175.011998080286;6182.876342473125;6196.969015154896;5892.234009578813;5892.646441213541;5935.584509084322;5949.10763385487;5953.851153721192;6879;6922.2731613180895;6923.93076069253;6945.513814032969;6947.513674042971;6606.47148228131;6635.701393437404;6657.800265992028;6665.300143484864;6676.699548520425;6577.408033277028;6678.23200331968;6784.050232967487;6853.657317134223;6889.138857642794;6516.348365163446;6535.934640653532;6537.640429776408;6538.313477084837;6542.640154791342;6545.37818907203;6643.40209381158;6650.068990341259;6664.600123992576;6665.933340666726;6263.091851681572;6329.683515824098;6380.553361264667;6430.2926678066315;6473.838154046187;6366.872662546816;6385;6416.871662566629;6430.067640192239;6436.420722135031;6277.2153480814895;6304.117423560613;6304.211973503312;6306.905396418965;6328.272248691459;6253.093301471352;6301.558890877594;6323.399277068671;6366.267879193952;6407.391297826699;6232.8130156095385;6236.18929539157;6249.125122482765;6255.624662520263;6264.624122552661 14:46:59 INFO - PID 1980 | |1;ext.html;736.78264911851;789.4118881433332;808.2604416958483;1123.8651361836576;1139.2139423797585;27013.864930675343;27590;27607.5858862117;37254.81372593133;38015.42976855349;39342.409863852066;39715.40426893599;39845.800770996175;39855.202895942064;39861;8382.916170838305;8540.145985401441;8560.743177704648;8575.442596231256;8582.527960962145;6044.455998960093;6193.907091393633;8385.454945428559;8535.57322133894;8656.740297791077;8607.913920860805;8633.740987770378;8634.78413039676;8638.481691098523;8645.481271123721;8548.615312310962;8585.656573737044;8621.13788621139;8621.439606425562;8621.741347759515;5928.7628494860155;6093.939060609381;6113.358097399753;6779.762708305186;8466.238038576557;26689.332766680793;26849.060282890005;36498.635013649786;36868;36942;3340.8329583520726;3383.9661603383897;3384.983075084609;3385.289089291252;3391.253924136686;29954.70045299563;29960;30058.699413006027;30082.54876176859;30092.097237082704;3042.863071161803;3083.398737246238;3096.1176064821416;3103.798253113563;3109.2848644811593;339647;350620;351154;351493.4850651512;351547;48884.75557622189;49075;49109.75445122751;49151;49272;51352.97294054098;51464.742676287126;51494.485055149715;51542.742286288325;51690;14048.85951140496;14175.78736318956;14194.361253743606;14199.503017394341;14207.005509614435;11584.536618535332;11590.478428470738;11593.24643898144;11604.245724027911;11639.59261425846;10133.341332813343;10302;10302.793944121076;11916.404179790976;11954.565452145798;5331.173668081454;5434.184872269191;5443.7822487100075;5447.972760136174;5451.509364157166;938.9061093890556;954.9570269337896;962.6438217859437;996.5864166371019;1005.6178652112151;22072.11711531553;22449.775502245095;23004.769952300263;23891.641625375632;24036.639450408256;1005.2360206243162;1060.3002018667642;1070.8447275145215;1094.5731164845718;1098.8132017557034;9712.757181070388;11498.712532186595;11939.403029848472;11945.522179112912;12127.514899404101;446023;446873;446918.76540617086;447330;447785;53363.46636533663;53408.73295633497;53438.73280633572;53639.463605364224;53644.19533706998;1445.0968144909432;1450.9709805804036;1491.9403223870925;1498.3781730581686;1506.8869834762436;20413;20476.69284960697;21352;23267.76732232656;23353.41616459602;37921.2415751689;37982;38016.809915950245;38022;38135.809320953216;439200.80399597797;439897;440256;442901;443124;31773.523397148598;32402.189945251557;32445.51331730073;32459.837700811346;32497.675023249463;40922.59077409307;41413.58586414097;42151.73544793661;42192;42214;27613.30966725848;28374.716252837206;29398.853005734836;29603.48370388455;29787.255318617208;28470.43059138847;29492;29731.851340743156;29775.255618609706;29843.32330017751;30931.69068309378;35230.47154292637;35330.823345884135;35448.759293424606;35467.645323546436;26503.469930601666;28398.43203135967;28620.57069143923;30076.699233008265;30596.541051884684;21838.672419914034;23691.52616947686;23915.641265380684;24036.759632403453;24081.638775418032 14:46:59 INFO - PID 1980 | |2;jquery.html;385.4218671992012;423.2100336447157;433.6249144490016;436.7292278787152;450.33575476172626;17241.46549957259;21555.353339399822;22385.328440146823;23425.648615270784;23437.296881093553;22805.657915131287;23043.42391440215;23565.646515302284;23740.76259237407;24062.917168727407;5685;5951.196588460546;6101.169649105273;6264.498840092804;6328.9683551582175;6137.110119032737;6248.31268560458;6252.562320637558;6262.812115636538;6263.1544741459775;6024.246969128859;6113.358097399776;6132.938670613304;6235.594686345373;6236.376362363772;5252.36971563411;5307.893842123136;6132.386761323876;6196.318404975472;6207.844803879894;5521.69630670317;5539.279893613804;5546.91679624806;5556.027695153332;5560.7219639017885;27150.5927411089;28127.578086328722;28150;28270.869165233365;28610.14169574916;2213.435573928654;2235.564065007324;2256.9887150564305;2257.762934891829;2260.1185537640335;17832.821671783247;18028.27886884523;18317.3588924387;18338.541536461562;18608.534786630305;2085.4265077103764;2112.408525612831;2114.0909408954144;2142.046789178816;2147.3665268745603;146737.2663136688;147034;149224.2538787299;149319;151218.48781512154;10614.575416983389;11111.33324003904;11822.822657660143;12444.937775311066;12492.875071249171;13971.510997115052;13987.650308742159;15911.204439777965;16578.336866525446;16600.335986560644;8783.604737786687;8830.42602230853;8946.507942063248;9094.181523662903;9113.863292050624;7019.92980070203;8534.588722399434;8563.972323321117;8772.473651580925;8786.780330491662;7520.887186692204;7768.8725441208535;7772.261635144652;7775;7784.338331241795;1815;2130.637791575436;2132.7227460430354;2134.2956824247963;2139.8074173324476;869.9826003480022;901.6123067081218;925.5141050948172;938.9953050234706;947.1428357336621;19041.333553325843;20954.37136885896;21024.264150754956;21138.47153821136;21143.894280528806;965.710286913923;969.9078587534173;1003.5734812704571;1004.2417974429259;1004.382304882492;10378.688639340831;10981.40640290789;11008.449577521253;11100.888991110147;11117.888821111846;600176;610839;614643;700774;704632;142133;143535;144544;145339.27330363492;146393;1181.8345431639577;1199.580146948561;1224.9938750306192;1230.2618428942742;1232.0328542094412;15113.848861511244;18481;18502.167402467192;18555.721664174776;18596.907015464836;56118.719406402706;59047;63755.3624463768;63855.680721596094;64653.70692586028;80550;81780.59109704413;84163;84770;85554.57222713847;8430.494170349799;8617.181367770052;8697.173768491983;9207.631694732403;9210.861837072584;13889.652758681113;13985.790213146607;14271;14299.57101286963;14496.927515362355;11754.647360579196;11811.822822657496;11984.981276591587;12160.635180944586;12263.816042759545;11182.944085279521;11288.887111129112;11306.208565400342;11334.773304534028;11716.9454749073;11918.40407979615;12156.392180390769;12355.75288494243;12407.0223146446;12435.37823108859;8382.245597896219;8669.869951950599;8910.643574257216;8965.59654815548;9271.211946984582;10649.307794993301;11646.76706465849;12087.81868271959;12120.575779847662;12201.145919785884 14:46:59 INFO - PID 1980 | |3;mootools.html;1334.2328161307248;1395.7487652222603;1415.2216281045423;1415.306499815091;1417.617243344297;10939;11453.083753299738;11453.770924581504;11462.656120316404;11469.197156199069;10884.782304353928;11234.775304493905;11336.376499292535;11347.262427942178;11352.091832653388;2472.159465781634;2508.109621084516;2510.3724068982756;2511.7864981476596;2512.57286261336;2064.504518915457;2352.882355882208;2435.6468312094735;2485.9254222373356;2486.813488988324;2129.765725770164;2154.407537927075;2155.7197564316616;2156.3423155937435;2157.870527768331;2129.37183530858;2348.3542025942916;2463.679721636194;2466.0259197616842;2468.123816045314;2431.9270421887372;2466.136852101768;2491.2401717476164;2497.2008957133726;2497.600383938561;10998.835017474745;11221.887781122166;11224.270422422516;11225.158113141548;11233.494492747765;5511.917321240185;6327.493800495995;6560.8846496095775;6562.737490500374;6569.704363303663;3917.1186483041374;4174.666026717855;4230.851920182809;4249.235137675249;4254.489461264637;1208.7401208740075;1213.2417239225483;1249.925004499733;1263.6019653809021;1263.9810402843964;15380.384784608714;15695.764563531442;15718.21408929549;15775.368985240577;15775.92112039444;5613.831585052455;5684.204211410406;5775.123084997393;5962.761489540457;5981.7607295707685;13214.603561893158;13260.204387736767;13264.336783160803;13273.137246078973;13275.800862987064;6594.604323740591;6596.4393026592315;6596.934030659728;6600;6609.339066093352;5860.150278209636;5896.970515147397;6098.298695649968;6174.25908890936;6177.1969643946895;4447.688661793708;4569.840055598104;4578.702384345007;4611.861644150681;4655.441347038377;1362.489066600025;1408.8802451791507;1409.2530958591963;1429.6640289531952;1439;716.9677364518609;726.596738809955;752.736542210233;759.4380158682535;784.6429874407131;5583.944160558423;6735.49483788708;6963.7562685305775;6974.442044636518;7010.579365238102;475.9619230461555;570.5920267009109;578.3926876779449;584.8070136854827;594.8839976204686;3929.7445666031617;4155.065110350147;4161.230172418052;4282.079352939133;4301.139772045541;13735.175889446666;14354.85645143556;14360.281985900872;14366.35351409189;14377.137371757728;12742.68143296425;14229.78655320171;14251.429942802171;14253.429862805579;14504;10228.846567301496;10500.527476263434;10510.527026283835;10512.894871051345;10531.315464494783;6394.6979087527525;6715.9434112222025;6908.274631163656;6929.1684997800585;6932.445404367538;12732.618021459371;12756.872431275568;12765.553205637758;12934.353282336035;13005.674858128623;13216.074874758675;13267.933660331637;13399;13406.865931340952;13441.865581344453;8948;9230.676926307548;9366.391184573253;9376.81246375055;9379.577918993798;10121.848172277274;10191.94904025475;10240.743981400525;10339.948300258451;10371.325863818827;8733.78165545841;8746.049516034795;8818.779530511789;8830.690925817566;8888;8382.622781974704;8517.701880434155;8598.871016934625;8599.097094805205;8622.26710729595;7788.961055194688;7864.5281283123195;7872.527648341118;7968.561729104955;7978.321842643209;5809.215755872903;5945.286565612154;6073.696315184311;6178.969105154445;6190.84215484532;5372.221027950847;5787.276590426197;5920.230370051866;6141.508679305734;6158.168647232566 14:46:59 INFO - PID 1980 | |4;prototype.html;377.1608171817706;389.9629535194157;404.47418356137024;404.8744889084386;409.25719818529365;5600.523955463786;5887.293524777027;5915.674637894914;6075.969620151898;6087.421694938985;4451.421315229016;5112.84960883801;5869.207656966309;5881.705914704269;5907.232059832227;3674.0998455378403;4031.1333063391367;4424.911501769962;4427.2916333386665;4449.644028477714;4200.2617670273485;4443;4445.377647129389;4463.553644635543;4468.19572476954;4354.97822510887;4363.847265345714;4374.321980093091;4393.934090988638;4422.270325396322;3599.3341231872055;4345.326474396458;4356.259435895905;4361.323994780814;4362.650987920959;3772.6604605585353;3785.280796648628;3787.394016957301;3788.3370410178386;3789.9258297330202;4689.953100468986;5013.6490445668815;5363.846772943797;5432.130859062531;5804.390538993429;4513.300438432048;4962.0324036812835;5196.454372290893;5200.867644265871;5207.661502002357;5898.911516327258;5948.910766338508;5949.613275137145;6331.810045698637;6357.904631430532;4244.87265382039;4291.206126866539;4303.376010478494;4308.3968244445805;4315.978420107911;5563.554915606742;6380.776672816475;6552.9344706553275;6704.5306828522025;6708.597484150967;3722.9813850930573;4009.278329900646;4259.637930775857;4308.870733877989;4347.043650396908;3907.651860108258;4426.247537918562;4453;4455.4876189238;4465.26323156682;4985.634696579867;5167.483251674803;5194.986977539419;5221.765020574083;5244.423113457517;5206.583473322125;6288.308286088527;6352.460040896575;6396.78461092391;6407.647579383085;1340.6983428728458;1408.936597853099;1479.977800332996;1512.6067222522004;1516.5753588995099;810.3881569415172;976.5166242709904;984.1093810101869;1003.9698809035741;1007.4861820471466;5348.3849357323625;5806.187133801272;5866;5911.379305172895;6026.282509484776;6434.356564343622;6502.187226596676;6518.6636739468295;6542.967285163544;6627.9337206627315;4913.754312284372;5537.252470916455;5695.5443564514735;5916.7337469813965;5945.464908158287;2527.0397249045245;3215.4694475411293;3397.8470968806464;3460.1349662584353;3461.3596484649916;6469.838254043687;6500.577462464925;6697.866042679119;6777.72889084441;6819.454443644497;5606.411326810708;5894.764209431661;6743.089682892844;6767.898481522781;6771.645670865846;6539.640319782363;6797.592144471348;6807.319268073153;6827.453803695693;6827.863442731117;5983.80323935206;6343.082182196242;6455.160829092188;6509.446697030616;6536.738530458729;5922.348541660328;6453.386928241809;6488;6546.247181573989;6676.49926255523;5248.6588371755715;5752.993226185485;6379.872402552016;6544.8363790905605;6779.932120703035;4312.310030395247;4321.438213032286;4352.563653994111;4376.1904047749995;4376.474823021258;5547.833564993057;5926.992411290004;6129.141920131275;6660.933390666031;6708.048458678166;5383.892322153457;6357.841053973688;6390.7443702251385;6405.231372235362;6413.102165696713;5415.804675241627;6056.727447264796;6352.364763523796;6373.6175829450385;6412.583182093149;5867.882642347215;5944.4352786485215;6203.410675985774;6284.151639528605;6291.620664332113;6329.177206963065;6403.911335073142;6420.518461115342;6422.775202867877;6426.518011149276;4894.853154405374;5924.733386997682;6215.321863097029;6227.252729672468;6291.496680265659 14:46:59 INFO - PID 1980 | |5;yui.html;711.189244261542;789.124072279769;827.3422629009934;828.6519661742068;842.4776638484141;15437;15772.842271577281;16174.353025878965;16935.153242337863;17039;12967.675808104803;14922.253887305646;15162.393504259844;15518.224088795572;15702.528924132293;947.1996163242084;965.512416229803;967.2793768642372;969.1132613658494;970.1656575345197;919.776696993001;964.1563631822156;982.4743762087285;984.1437948984394;1007.2143727892219;1058.751193469534;1062.9468526573678;1064.5794911010184;1065.0467831291028;1065.275612583447;1056.1761825775907;1059.417320473744;1060.2631171335925;1060.6287799270272;1060.8833028366912;783.2402569507568;784.2392878907451;784.4038530716697;785.6346798738614;785.6464590934103;16310.266038028318;16680.165991700487;16778.412755553498;16811.915940420342;17119.400820971332;12228.938855305756;14778;14894.776578351333;15031.774523382159;15795.289211985373;13025.86974130256;13303.268320242383;13440.529581464602;13572.117812342167;13572.864271357259;3373.089265898194;3378.847951842173;3390.3897298486263;3404;3413.4367829308135;108088.45955770249;108815.82368352589;110268.89731102747;110778.89221107766;110946;11556.711082223012;11564.363959982113;11667.474963626657;11874.584389546324;12070.818937715942;23445.296641100795;24083;24703.87648061748;24771.87614061954;24780.628290575656;8041.396895232764;8051.798705032304;8057.395695322874;8151.836963260702;8168.346532277523;3619.2761447710564;4132.938005929914;4136.152088821785;4202.516710578323;4228.616429002173;4108.876733697994;4203.810828512725;4217.240896638573;4431.446069241345;4616.076784643018;815.8980127484065;915.0529202275612;941.1717688434134;944.3106532231509;945.1588086602928;608.3308360803081;615.7198474694003;619.6498978077371;641.7016087519326;663.1875951958908;8245.432472236611;10106.242031847642;10268.229882758824;10310.639127630497;10485.475726213812;598.931122920861;611.1993288791608;614.1954040207343;627.4541149200224;661.2065521374274;4454.398656181439;4517.864464066083;4730.834420795324;4735.171345014609;4782.139214941344;93964.53017735004;95991;97388;97460.51269743606;99129;84343;86079.13920860837;86878.56560717156;87025;87268;73017.6349118251;77583;77638.61180694061;78228;78639.21360786434;9430.245580353418;9573.377730447499;9586.089321514444;9592.376495528048;9604.75988100303;24499.632505512076;39554.60445395509;40278.59721402748;40392.596074038884;40666.59333406628;50441.73895652638;50974.49025509697;51181.62592927838;52174;64772.67613661902;13509.662258443617;13688.45246190181;13810.309484525935;14050.826592505146;14584.48954286595;14996.250187490363;15098.471553495576;15221.31504082341;15458.381664733284;15554.9222253888;13369.732605347644;13815.240161791198;13996;14193.889406942393;14634.780478293047;11732.70668233301;11874.168808183344;12597.559085431965;12905.612831614691;14856.405743770129;13346.866531334563;13369.866301336862;13458.664164022535;13515.797263040866;13887.04516410261;10025.899741002497;10227.795444090927;10357.689269321932;10830.187735919995;11199.328040317607;10145.695629130843;11066.225364224427;11073.944630276797;11299.43502824839;11334.263272887236 14:46:59 INFO - PID 1980 | __end_tp_report 14:46:59 INFO - PID 1980 | __start_cc_report 14:46:59 INFO - PID 1980 | _x_x_mozilla_cycle_collect,1929 14:46:59 INFO - PID 1980 | __end_cc_report 14:46:59 INFO - PID 1980 | __startTimestamp1491428819229__endTimestamp 14:46:59 INFO - PID 1980 | 14:46:59 INFO - PID 1980 | ------- Summary: start ------- 14:46:59 INFO - PID 1980 | Number of tests: 6 14:46:59 INFO - PID 1980 | 14:46:59 INFO - PID 1980 | [#0] dojo.html Cycles:165 Average:6051.75 Median:6296.91 stddev:717.70 (11.4%) stddev-sans-first:690.51 14:46:59 INFO - PID 1980 | Values: 3460.2 3461.1 3479.4 3493.8 3533.2 6096.1 6167.0 6179.4 6205.5 6207.3 5020.8 5709.4 6005.2 6130.3 6164.7 5297.5 5313.2 5327.8 5336.5 5474.8 5232.5 5333.1 5419.4 5427.8 5469.7 5313.5 5341.8 5342.7 5345.9 5347.4 4408.7 5319.3 5324.7 5325.4 5344.1 5221.4 5270.7 5278.5 5284.8 5287.4 6011.5 6031.0 6041.2 6043.3 6049.6 5393.5 6327.4 6389.8 6391.2 6395.8 6457.3 6565.4 6661.5 6665.5 6686.1 6294.6 6295.6 6296.9 6305.8 6317.5 6496.9 6755.7 6780.0 6810.8 6834.5 6335.2 6371.4 6397.2 6436.7 6476.7 6612.1 6614.3 6619.5 6619.8 6633.5 6486.4 6495.1 6507.7 6516.9 6526.8 6278.6 6373.4 6373.6 6412.7 6495.1 5260.6 5338.9 5341.4 5345.8 5352.4 4685.0 4691.3 4695.4 4699.7 4727.5 6192.4 6221.0 6275.0 6290.6 6291.7 6420.7 6436.3 6437.9 6464.9 6468.5 6166.6 6166.8 6175.0 6182.9 6197.0 5892.2 5892.6 5935.6 5949.1 5953.9 6879.0 6922.3 6923.9 6945.5 6947.5 6606.5 6635.7 6657.8 6665.3 6676.7 6577.4 6678.2 6784.1 6853.7 6889.1 6516.3 6535.9 6537.6 6538.3 6542.6 6545.4 6643.4 6650.1 6664.6 6665.9 6263.1 6329.7 6380.6 6430.3 6473.8 6366.9 6385.0 6416.9 6430.1 6436.4 6277.2 6304.1 6304.2 6306.9 6328.3 6253.1 6301.6 6323.4 6366.3 6407.4 6232.8 6236.2 6249.1 6255.6 6264.6 14:46:59 INFO - PID 1980 | 14:46:59 INFO - PID 1980 | [#1] ext.html Cycles:180 Average:53660.03 Median:351323.74 stddev:110288.68 (31.4%) stddev-sans-first:110526.49 14:46:59 INFO - PID 1980 | Values: 736.8 789.4 808.3 1123.9 1139.2 27013.9 27590.0 27607.6 37254.8 38015.4 39342.4 39715.4 39845.8 39855.2 39861.0 8382.9 8540.1 8560.7 8575.4 8582.5 6044.5 6193.9 8385.5 8535.6 8656.7 8607.9 8633.7 8634.8 8638.5 8645.5 8548.6 8585.7 8621.1 8621.4 8621.7 5928.8 6093.9 6113.4 6779.8 8466.2 26689.3 26849.1 36498.6 36868.0 36942.0 3340.8 3384.0 3385.0 3385.3 3391.3 29954.7 29960.0 30058.7 30082.5 30092.1 3042.9 3083.4 3096.1 3103.8 3109.3 339647.0 350620.0 351154.0 351493.5 351547.0 48884.8 49075.0 49109.8 49151.0 49272.0 51353.0 51464.7 51494.5 51542.7 51690.0 14048.9 14175.8 14194.4 14199.5 14207.0 11584.5 11590.5 11593.2 11604.2 11639.6 10133.3 10302.0 10302.8 11916.4 11954.6 5331.2 5434.2 5443.8 5448.0 5451.5 938.9 955.0 962.6 996.6 1005.6 22072.1 22449.8 23004.8 23891.6 24036.6 1005.2 1060.3 1070.8 1094.6 1098.8 9712.8 11498.7 11939.4 11945.5 12127.5 446023.0 446873.0 446918.8 447330.0 447785.0 53363.5 53408.7 53438.7 53639.5 53644.2 1445.1 1451.0 1491.9 1498.4 1506.9 20413.0 20476.7 21352.0 23267.8 23353.4 37921.2 37982.0 38016.8 38022.0 38135.8 439200.8 439897.0 440256.0 442901.0 443124.0 31773.5 32402.2 32445.5 32459.8 32497.7 40922.6 41413.6 42151.7 42192.0 42214.0 27613.3 28374.7 29398.9 29603.5 29787.3 28470.4 29492.0 29731.9 29775.3 29843.3 30931.7 35230.5 35330.8 35448.8 35467.6 26503.5 28398.4 28620.6 30076.7 30596.5 21838.7 23691.5 23915.6 24036.8 24081.6 14:46:59 INFO - PID 1980 | 14:46:59 INFO - PID 1980 | [#2] jquery.html Cycles:180 Average:38779.37 Median:12532.89 stddev:108831.50 (868.4%) stddev-sans-first:109098.61 14:46:59 INFO - PID 1980 | Values: 385.4 423.2 433.6 436.7 450.3 17241.5 21555.4 22385.3 23425.6 23437.3 22805.7 23043.4 23565.6 23740.8 24062.9 5685.0 5951.2 6101.2 6264.5 6329.0 6137.1 6248.3 6252.6 6262.8 6263.2 6024.2 6113.4 6132.9 6235.6 6236.4 5252.4 5307.9 6132.4 6196.3 6207.8 5521.7 5539.3 5546.9 5556.0 5560.7 27150.6 28127.6 28150.0 28270.9 28610.1 2213.4 2235.6 2257.0 2257.8 2260.1 17832.8 18028.3 18317.4 18338.5 18608.5 2085.4 2112.4 2114.1 2142.0 2147.4 146737.3 147034.0 149224.3 149319.0 151218.5 10614.6 11111.3 11822.8 12444.9 12492.9 13971.5 13987.7 15911.2 16578.3 16600.3 8783.6 8830.4 8946.5 9094.2 9113.9 7019.9 8534.6 8564.0 8772.5 8786.8 7520.9 7768.9 7772.3 7775.0 7784.3 1815.0 2130.6 2132.7 2134.3 2139.8 870.0 901.6 925.5 939.0 947.1 19041.3 20954.4 21024.3 21138.5 21143.9 965.7 969.9 1003.6 1004.2 1004.4 10378.7 10981.4 11008.4 11100.9 11117.9 600176.0 610839.0 614643.0 700774.0 704632.0 142133.0 143535.0 144544.0 145339.3 146393.0 1181.8 1199.6 1225.0 1230.3 1232.0 15113.8 18481.0 18502.2 18555.7 18596.9 56118.7 59047.0 63755.4 63855.7 64653.7 80550.0 81780.6 84163.0 84770.0 85554.6 8430.5 8617.2 8697.2 9207.6 9210.9 13889.7 13985.8 14271.0 14299.6 14496.9 11754.6 11811.8 11985.0 12160.6 12263.8 11182.9 11288.9 11306.2 11334.8 11716.9 11918.4 12156.4 12355.8 12407.0 12435.4 8382.2 8669.9 8910.6 8965.6 9271.2 10649.3 11646.8 12087.8 12120.6 12201.1 14:46:59 INFO - PID 1980 | 14:46:59 INFO - PID 1980 | [#3] mootools.html Cycles:180 Average:7015.34 Median:2502.86 stddev:4392.11 (175.5%) stddev-sans-first:4383.68 14:46:59 INFO - PID 1980 | Values: 1334.2 1395.7 1415.2 1415.3 1417.6 10939.0 11453.1 11453.8 11462.7 11469.2 10884.8 11234.8 11336.4 11347.3 11352.1 2472.2 2508.1 2510.4 2511.8 2512.6 2064.5 2352.9 2435.6 2485.9 2486.8 2129.8 2154.4 2155.7 2156.3 2157.9 2129.4 2348.4 2463.7 2466.0 2468.1 2431.9 2466.1 2491.2 2497.2 2497.6 10998.8 11221.9 11224.3 11225.2 11233.5 5511.9 6327.5 6560.9 6562.7 6569.7 3917.1 4174.7 4230.9 4249.2 4254.5 1208.7 1213.2 1249.9 1263.6 1264.0 15380.4 15695.8 15718.2 15775.4 15775.9 5613.8 5684.2 5775.1 5962.8 5981.8 13214.6 13260.2 13264.3 13273.1 13275.8 6594.6 6596.4 6596.9 6600.0 6609.3 5860.2 5897.0 6098.3 6174.3 6177.2 4447.7 4569.8 4578.7 4611.9 4655.4 1362.5 1408.9 1409.3 1429.7 1439.0 717.0 726.6 752.7 759.4 784.6 5583.9 6735.5 6963.8 6974.4 7010.6 476.0 570.6 578.4 584.8 594.9 3929.7 4155.1 4161.2 4282.1 4301.1 13735.2 14354.9 14360.3 14366.4 14377.1 12742.7 14229.8 14251.4 14253.4 14504.0 10228.8 10500.5 10510.5 10512.9 10531.3 6394.7 6715.9 6908.3 6929.2 6932.4 12732.6 12756.9 12765.6 12934.4 13005.7 13216.1 13267.9 13399.0 13406.9 13441.9 8948.0 9230.7 9366.4 9376.8 9379.6 10121.8 10191.9 10240.7 10339.9 10371.3 8733.8 8746.0 8818.8 8830.7 8888.0 8382.6 8517.7 8598.9 8599.1 8622.3 7789.0 7864.5 7872.5 7968.6 7978.3 5809.2 5945.3 6073.7 6179.0 6190.8 5372.2 5787.3 5920.2 6141.5 6158.2 14:46:59 INFO - PID 1980 | 14:46:59 INFO - PID 1980 | [#4] prototype.html Cycles:180 Average:5085.09 Median:5724.27 stddev:1600.17 (28.0%) stddev-sans-first:1565.16 14:46:59 INFO - PID 1980 | Values: 377.2 390.0 404.5 404.9 409.3 5600.5 5887.3 5915.7 6076.0 6087.4 4451.4 5112.8 5869.2 5881.7 5907.2 3674.1 4031.1 4424.9 4427.3 4449.6 4200.3 4443.0 4445.4 4463.6 4468.2 4355.0 4363.8 4374.3 4393.9 4422.3 3599.3 4345.3 4356.3 4361.3 4362.7 3772.7 3785.3 3787.4 3788.3 3789.9 4690.0 5013.6 5363.8 5432.1 5804.4 4513.3 4962.0 5196.5 5200.9 5207.7 5898.9 5948.9 5949.6 6331.8 6357.9 4244.9 4291.2 4303.4 4308.4 4316.0 5563.6 6380.8 6552.9 6704.5 6708.6 3723.0 4009.3 4259.6 4308.9 4347.0 3907.7 4426.2 4453.0 4455.5 4465.3 4985.6 5167.5 5195.0 5221.8 5244.4 5206.6 6288.3 6352.5 6396.8 6407.6 1340.7 1408.9 1480.0 1512.6 1516.6 810.4 976.5 984.1 1004.0 1007.5 5348.4 5806.2 5866.0 5911.4 6026.3 6434.4 6502.2 6518.7 6543.0 6627.9 4913.8 5537.3 5695.5 5916.7 5945.5 2527.0 3215.5 3397.8 3460.1 3461.4 6469.8 6500.6 6697.9 6777.7 6819.5 5606.4 5894.8 6743.1 6767.9 6771.6 6539.6 6797.6 6807.3 6827.5 6827.9 5983.8 6343.1 6455.2 6509.4 6536.7 5922.3 6453.4 6488.0 6546.2 6676.5 5248.7 5753.0 6379.9 6544.8 6779.9 4312.3 4321.4 4352.6 4376.2 4376.5 5547.8 5927.0 6129.1 6660.9 6708.0 5383.9 6357.8 6390.7 6405.2 6413.1 5415.8 6056.7 6352.4 6373.6 6412.6 5867.9 5944.4 6203.4 6284.2 6291.6 6329.2 6403.9 6420.5 6422.8 6426.5 4894.9 5924.7 6215.3 6227.3 6291.5 14:46:59 INFO - PID 1980 | 14:46:59 INFO - PID 1980 | [#5] yui.html Cycles:180 Average:19876.87 Median:14076.86 stddev:28147.44 (200.0%) stddev-sans-first:28189.61 14:46:59 INFO - PID 1980 | Values: 711.2 789.1 827.3 828.7 842.5 15437.0 15772.8 16174.4 16935.2 17039.0 12967.7 14922.3 15162.4 15518.2 15702.5 947.2 965.5 967.3 969.1 970.2 919.8 964.2 982.5 984.1 1007.2 1058.8 1062.9 1064.6 1065.0 1065.3 1056.2 1059.4 1060.3 1060.6 1060.9 783.2 784.2 784.4 785.6 785.6 16310.3 16680.2 16778.4 16811.9 17119.4 12228.9 14778.0 14894.8 15031.8 15795.3 13025.9 13303.3 13440.5 13572.1 13572.9 3373.1 3378.8 3390.4 3404.0 3413.4 108088.5 108815.8 110268.9 110778.9 110946.0 11556.7 11564.4 11667.5 11874.6 12070.8 23445.3 24083.0 24703.9 24771.9 24780.6 8041.4 8051.8 8057.4 8151.8 8168.3 3619.3 4132.9 4136.2 4202.5 4228.6 4108.9 4203.8 4217.2 4431.4 4616.1 815.9 915.1 941.2 944.3 945.2 608.3 615.7 619.6 641.7 663.2 8245.4 10106.2 10268.2 10310.6 10485.5 598.9 611.2 614.2 627.5 661.2 4454.4 4517.9 4730.8 4735.2 4782.1 93964.5 95991.0 97388.0 97460.5 99129.0 84343.0 86079.1 86878.6 87025.0 87268.0 73017.6 77583.0 77638.6 78228.0 78639.2 9430.2 9573.4 9586.1 9592.4 9604.8 24499.6 39554.6 40278.6 40392.6 40666.6 50441.7 50974.5 51181.6 52174.0 64772.7 13509.7 13688.5 13810.3 14050.8 14584.5 14996.3 15098.5 15221.3 15458.4 15554.9 13369.7 13815.2 13996.0 14193.9 14634.8 11732.7 11874.2 12597.6 12905.6 14856.4 13346.9 13369.9 13458.7 13515.8 13887.0 10025.9 10227.8 10357.7 10830.2 11199.3 10145.7 11066.2 11073.9 11299.4 11334.3 14:46:59 INFO - PID 1980 | -------- Summary: end -------- 14:46:59 INFO - PID 1980 | 14:46:59 INFO - TEST-INFO | 1980: exit 0 14:46:59 INFO - TEST-OK | dromaeo_css | took 1121816ms 14:46:59 INFO - TEST-START | kraken 14:46:59 INFO - operating with platform_type : mac_ 14:46:59 INFO - Initialising browser for kraken test... 14:46:59 INFO - TEST-INFO | started process 1986 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmp4qtcJN/profile http://localhost:54348/getInfo.html) 14:47:02 INFO - PID 1986 | __metrics Screen width/height:1600/1200 14:47:02 INFO - PID 1986 | colorDepth:24 14:47:02 INFO - PID 1986 | Browser inner width/height: 1024/689 14:47:02 INFO - PID 1986 | __metrics 14:47:02 INFO - PID 1986 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:47:02 INFO - PID 1986 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:47:03 INFO - TEST-INFO | 1986: exit 0 14:47:03 INFO - Browser initialized. 14:47:03 INFO - Running cycle 1/1 for kraken test... 14:47:03 INFO - TEST-INFO | started process 1987 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmp4qtcJN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:47:16 INFO - PID 1987 | RSS: Main: 239312896 14:47:16 INFO - PID 1987 | 14:47:19 INFO - PID 1987 | Cycle 1(1): loaded http://localhost:54348/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54348/tests/kraken/driver.html?testName=audio-beat-detection) 14:47:20 INFO - PID 1987 | RSS: Main: 292220928 14:47:20 INFO - PID 1987 | 14:47:22 INFO - PID 1987 | Cycle 1(1): loaded http://localhost:54348/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54348/tests/kraken/driver.html?testName=audio-dft) 14:47:23 INFO - PID 1987 | RSS: Main: 293359616 14:47:23 INFO - PID 1987 | 14:47:27 INFO - PID 1987 | Cycle 1(1): loaded http://localhost:54348/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54348/tests/kraken/driver.html?testName=audio-fft) 14:47:27 INFO - PID 1987 | RSS: Main: 386609152 14:47:27 INFO - PID 1987 | 14:47:29 INFO - PID 1987 | Cycle 1(1): loaded http://localhost:54348/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54348/tests/kraken/driver.html?testName=audio-oscillator) 14:47:30 INFO - PID 1987 | RSS: Main: 288468992 14:47:30 INFO - PID 1987 | 14:47:32 INFO - PID 1987 | Cycle 1(1): loaded http://localhost:54348/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54348/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:47:33 INFO - PID 1987 | RSS: Main: 291426304 14:47:33 INFO - PID 1987 | 14:47:36 INFO - PID 1987 | Cycle 1(1): loaded http://localhost:54348/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54348/tests/kraken/driver.html?testName=imaging-darkroom) 14:47:36 INFO - PID 1987 | RSS: Main: 333598720 14:47:36 INFO - PID 1987 | 14:47:40 INFO - PID 1987 | Cycle 1(1): loaded http://localhost:54348/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54348/tests/kraken/driver.html?testName=imaging-desaturate) 14:47:40 INFO - PID 1987 | RSS: Main: 342257664 14:47:40 INFO - PID 1987 | 14:47:43 INFO - PID 1987 | Cycle 1(1): loaded http://localhost:54348/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54348/tests/kraken/driver.html?testName=json-parse-financial) 14:47:44 INFO - PID 1987 | RSS: Main: 346509312 14:47:44 INFO - PID 1987 | 14:47:46 INFO - PID 1987 | Cycle 1(1): loaded http://localhost:54348/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54348/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:47:46 INFO - PID 1987 | RSS: Main: 309526528 14:47:46 INFO - PID 1987 | 14:47:48 INFO - PID 1987 | Cycle 1(1): loaded http://localhost:54348/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54348/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:47:49 INFO - PID 1987 | RSS: Main: 313942016 14:47:49 INFO - PID 1987 | 14:47:51 INFO - PID 1987 | Cycle 1(1): loaded http://localhost:54348/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54348/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:47:52 INFO - PID 1987 | RSS: Main: 316219392 14:47:52 INFO - PID 1987 | 14:47:55 INFO - PID 1987 | Cycle 1(1): loaded http://localhost:54348/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54348/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:47:56 INFO - PID 1987 | RSS: Main: 333836288 14:47:56 INFO - PID 1987 | 14:47:59 INFO - PID 1987 | Cycle 1(1): loaded http://localhost:54348/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54348/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:48:00 INFO - PID 1987 | RSS: Main: 316567552 14:48:00 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | Cycle 1(1): loaded http://localhost:54348/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54348/tests/kraken/driver.html?testName=ai-astar) 14:48:02 INFO - PID 1987 | RSS: Main: 383283200 14:48:02 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | __start_tp_report 14:48:02 INFO - PID 1987 | _x_x_mozilla_page_load 14:48:02 INFO - PID 1987 | _x_x_mozilla_page_load_details 14:48:02 INFO - PID 1987 | |i|pagename|runs| 14:48:02 INFO - PID 1987 | |0;ai-astar;112;122;124;123;124;124;124;121;124;123 14:48:02 INFO - PID 1987 | |1;audio-beat-detection;158;117;118;165;118;121;120;119;146;120 14:48:02 INFO - PID 1987 | |2;audio-dft;214;223;214;222;212;210;209;211;245;302 14:48:02 INFO - PID 1987 | |3;audio-fft;87;65;66;102;66;64;63;61;84;64 14:48:02 INFO - PID 1987 | |4;audio-oscillator;99;77;96;76;76;74;91;73;74;92 14:48:02 INFO - PID 1987 | |5;imaging-gaussian-blur;91;90;91;91;90;91;91;106;139;90 14:48:02 INFO - PID 1987 | |6;imaging-darkroom;101;107;156;101;100;99;102;101;101;100 14:48:02 INFO - PID 1987 | |7;imaging-desaturate;101;102;100;100;101;102;102;101;102;102 14:48:02 INFO - PID 1987 | |8;json-parse-financial;53;52;51;51;52;53;52;54;52;54 14:48:02 INFO - PID 1987 | |9;json-stringify-tinderbox;66;63;65;73;68;67;65;64;62;64 14:48:02 INFO - PID 1987 | |10;stanford-crypto-aes;92;92;94;110;117;118;94;92;89;89 14:48:02 INFO - PID 1987 | |11;stanford-crypto-ccm;159;167;170;156;148;152;172;177;218;206 14:48:02 INFO - PID 1987 | |12;stanford-crypto-pbkdf2;202;214;228;249;249;208;210;222;186;251 14:48:02 INFO - PID 1987 | |13;stanford-crypto-sha256-iterative;72;74;80;98;105;101;83;72;73;73 14:48:02 INFO - PID 1987 | __end_tp_report 14:48:02 INFO - PID 1987 | __start_cc_report 14:48:02 INFO - PID 1987 | _x_x_mozilla_cycle_collect,2156 14:48:02 INFO - PID 1987 | __end_cc_report 14:48:02 INFO - PID 1987 | __startTimestamp1491428882869__endTimestamp 14:48:02 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | ------- Summary: start ------- 14:48:02 INFO - PID 1987 | Number of tests: 14 14:48:02 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | [#0] ai-astar Cycles:10 Average:122.10 Median:124.00 stddev:3.70 (3.0%) stddev-sans-first:1.09 14:48:02 INFO - PID 1987 | Values: 112.0 122.0 124.0 123.0 124.0 124.0 124.0 121.0 124.0 123.0 14:48:02 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:120.50 stddev:18.63 (15.5%) stddev-sans-first:16.83 14:48:02 INFO - PID 1987 | Values: 158.0 117.0 118.0 165.0 118.0 121.0 120.0 119.0 146.0 120.0 14:48:02 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | [#2] audio-dft Cycles:10 Average:226.20 Median:218.00 stddev:28.70 (13.2%) stddev-sans-first:30.10 14:48:02 INFO - PID 1987 | Values: 214.0 223.0 214.0 222.0 212.0 210.0 209.0 211.0 245.0 302.0 14:48:02 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | [#3] audio-fft Cycles:10 Average:72.20 Median:65.50 stddev:13.82 (21.1%) stddev-sans-first:13.58 14:48:02 INFO - PID 1987 | Values: 87.0 65.0 66.0 102.0 66.0 64.0 63.0 61.0 84.0 64.0 14:48:02 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | [#4] audio-oscillator Cycles:10 Average:82.80 Median:84.00 stddev:10.36 (12.3%) stddev-sans-first:9.18 14:48:02 INFO - PID 1987 | Values: 99.0 77.0 96.0 76.0 76.0 74.0 91.0 73.0 74.0 92.0 14:48:02 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | [#5] imaging-gaussian-blur Cycles:10 Average:97.00 Median:91.00 stddev:15.53 (17.1%) stddev-sans-first:16.32 14:48:02 INFO - PID 1987 | Values: 91.0 90.0 91.0 91.0 90.0 91.0 91.0 106.0 139.0 90.0 14:48:02 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | [#6] imaging-darkroom Cycles:10 Average:106.80 Median:101.50 stddev:17.42 (17.2%) stddev-sans-first:18.35 14:48:02 INFO - PID 1987 | Values: 101.0 107.0 156.0 101.0 100.0 99.0 102.0 101.0 101.0 100.0 14:48:02 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | [#7] imaging-desaturate Cycles:10 Average:101.30 Median:102.00 stddev:0.82 (0.8%) stddev-sans-first:0.87 14:48:02 INFO - PID 1987 | Values: 101.0 102.0 100.0 100.0 101.0 102.0 102.0 101.0 102.0 102.0 14:48:02 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:52.50 stddev:1.07 (2.0%) stddev-sans-first:1.12 14:48:02 INFO - PID 1987 | Values: 53.0 52.0 51.0 51.0 52.0 53.0 52.0 54.0 52.0 54.0 14:48:02 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:65.50 stddev:3.13 (4.8%) stddev-sans-first:3.32 14:48:02 INFO - PID 1987 | Values: 66.0 63.0 65.0 73.0 68.0 67.0 65.0 64.0 62.0 64.0 14:48:02 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | [#10] stanford-crypto-aes Cycles:10 Average:98.70 Median:92.00 stddev:11.56 (12.6%) stddev-sans-first:12.00 14:48:02 INFO - PID 1987 | Values: 92.0 92.0 94.0 110.0 117.0 118.0 94.0 92.0 89.0 89.0 14:48:02 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | [#11] stanford-crypto-ccm Cycles:10 Average:172.50 Median:171.00 stddev:22.91 (13.4%) stddev-sans-first:23.77 14:48:02 INFO - PID 1987 | Values: 159.0 167.0 170.0 156.0 148.0 152.0 172.0 177.0 218.0 206.0 14:48:02 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:221.90 Median:225.00 stddev:22.20 (9.9%) stddev-sans-first:22.35 14:48:02 INFO - PID 1987 | Values: 202.0 214.0 228.0 249.0 249.0 208.0 210.0 222.0 186.0 251.0 14:48:02 INFO - PID 1987 | 14:48:02 INFO - PID 1987 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.10 Median:73.50 stddev:13.19 (17.9%) stddev-sans-first:13.36 14:48:02 INFO - PID 1987 | Values: 72.0 74.0 80.0 98.0 105.0 101.0 83.0 72.0 73.0 73.0 14:48:02 INFO - PID 1987 | -------- Summary: end -------- 14:48:02 INFO - PID 1987 | 14:48:04 INFO - TEST-INFO | 1987: exit 0 14:48:04 INFO - TEST-OK | kraken | took 64206ms 14:48:04 INFO - SUITE-END | took 1186s 14:48:04 INFO - Completed test suite (00:19:46) 14:48:04 INFO - javascript benchmark 14:48:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7661.419717850008, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3460.238747475555, 3461.100113970367, 3479.4085005549086, 3493.755437119401, 3533.205028868505, 6096.073160812939, 6167.0, 6179.412955769206, 6205.534584906133, 6207.286162091361, 5020.76991137171, 5709.400512946143, 6005.219321488212, 6130.2950160731525, 6164.660943648098, 5297.496737809921, 5313.176457649051, 5327.840164795043, 5336.519713225809, 5474.808381706641, 5232.502912223333, 5333.093374126388, 5419.403865574804, 5427.83716488506, 5469.699166545838, 5313.521783039545, 5341.813036543741, 5342.652727572696, 5345.946540534584, 5347.358317001946, 4408.691391602589, 5319.335083114611, 5324.733763311819, 5325.4142044375085, 5344.064788662007, 5221.399539052982, 5270.657407268515, 5278.498542638482, 5284.762185701614, 5287.365516138049, 6011.549133814981, 6031.0, 6041.24484439445, 6043.335233124353, 6049.637021778707, 5393.487618676219, 6327.430531252164, 6389.7763578275, 6391.169148010729, 6395.8401039973905, 6457.2896981332015, 6565.409113179837, 6661.467082633378, 6665.533412661116, 6686.063951046857, 6294.590851594678, 6295.5907865988565, 6296.905546416808, 6305.8108256752375, 6317.4630156437215, 6496.935030649727, 6755.729770809211, 6779.966100169468, 6810.829729256809, 6834.453243740489, 6335.239771227482, 6371.39471750183, 6397.200349956255, 6436.7425302988295, 6476.740930362733, 6612.107365505595, 6614.338566143347, 6619.5035372347265, 6619.801405957829, 6633.4693224541925, 6486.35136486357, 6495.0582165586675, 6507.739690412425, 6516.934830651728, 6526.771562995273, 6278.591891527036, 6373.426391624776, 6373.585716928477, 6412.67936603168, 6495.090687303781, 5260.631755777059, 5338.866528336823, 5341.359036915517, 5345.786168553292, 5352.437994010573, 4684.953150468452, 4691.3432119503295, 4695.413073365829, 4699.741514216683, 4727.527247275314, 6192.411720886508, 6221.0, 6274.964630835952, 6290.622562646256, 6291.748330066837, 6420.7431702731365, 6436.3241859605005, 6437.871242575122, 6464.935350646528, 6468.482521398277, 6166.630002199882, 6166.81499555014, 6175.011998080286, 6182.876342473125, 6196.969015154896, 5892.234009578813, 5892.646441213541, 5935.584509084322, 5949.10763385487, 5953.851153721192, 6879.0, 6922.2731613180895, 6923.93076069253, 6945.513814032969, 6947.513674042971, 6606.47148228131, 6635.701393437404, 6657.800265992028, 6665.300143484864, 6676.699548520425, 6577.408033277028, 6678.23200331968, 6784.050232967487, 6853.657317134223, 6889.138857642794, 6516.348365163446, 6535.934640653532, 6537.640429776408, 6538.313477084837, 6542.640154791342, 6545.37818907203, 6643.40209381158, 6650.068990341259, 6664.600123992576, 6665.933340666726, 6263.091851681572, 6329.683515824098, 6380.553361264667, 6430.2926678066315, 6473.838154046187, 6366.872662546816, 6385.0, 6416.871662566629, 6430.067640192239, 6436.420722135031, 6277.2153480814895, 6304.117423560613, 6304.211973503312, 6306.905396418965, 6328.272248691459, 6253.093301471352, 6301.558890877594, 6323.399277068671, 6366.267879193952, 6407.391297826699, 6232.8130156095385, 6236.18929539157, 6249.125122482765, 6255.624662520263, 6264.624122552661], "value": 6003.130106156426, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.78264911851, 789.4118881433332, 808.2604416958483, 1123.8651361836576, 1139.2139423797585, 27013.864930675343, 27590.0, 27607.5858862117, 37254.81372593133, 38015.42976855349, 39342.409863852066, 39715.40426893599, 39845.800770996175, 39855.202895942064, 39861.0, 8382.916170838305, 8540.145985401441, 8560.743177704648, 8575.442596231256, 8582.527960962145, 6044.455998960093, 6193.907091393633, 8385.454945428559, 8535.57322133894, 8656.740297791077, 8607.913920860805, 8633.740987770378, 8634.78413039676, 8638.481691098523, 8645.481271123721, 8548.615312310962, 8585.656573737044, 8621.13788621139, 8621.439606425562, 8621.741347759515, 5928.7628494860155, 6093.939060609381, 6113.358097399753, 6779.762708305186, 8466.238038576557, 26689.332766680793, 26849.060282890005, 36498.635013649786, 36868.0, 36942.0, 3340.8329583520726, 3383.9661603383897, 3384.983075084609, 3385.289089291252, 3391.253924136686, 29954.70045299563, 29960.0, 30058.699413006027, 30082.54876176859, 30092.097237082704, 3042.863071161803, 3083.398737246238, 3096.1176064821416, 3103.798253113563, 3109.2848644811593, 339647.0, 350620.0, 351154.0, 351493.4850651512, 351547.0, 48884.75557622189, 49075.0, 49109.75445122751, 49151.0, 49272.0, 51352.97294054098, 51464.742676287126, 51494.485055149715, 51542.742286288325, 51690.0, 14048.85951140496, 14175.78736318956, 14194.361253743606, 14199.503017394341, 14207.005509614435, 11584.536618535332, 11590.478428470738, 11593.24643898144, 11604.245724027911, 11639.59261425846, 10133.341332813343, 10302.0, 10302.793944121076, 11916.404179790976, 11954.565452145798, 5331.173668081454, 5434.184872269191, 5443.7822487100075, 5447.972760136174, 5451.509364157166, 938.9061093890556, 954.9570269337896, 962.6438217859437, 996.5864166371019, 1005.6178652112151, 22072.11711531553, 22449.775502245095, 23004.769952300263, 23891.641625375632, 24036.639450408256, 1005.2360206243162, 1060.3002018667642, 1070.8447275145215, 1094.5731164845718, 1098.8132017557034, 9712.757181070388, 11498.712532186595, 11939.403029848472, 11945.522179112912, 12127.514899404101, 446023.0, 446873.0, 446918.76540617086, 447330.0, 447785.0, 53363.46636533663, 53408.73295633497, 53438.73280633572, 53639.463605364224, 53644.19533706998, 1445.0968144909432, 1450.9709805804036, 1491.9403223870925, 1498.3781730581686, 1506.8869834762436, 20413.0, 20476.69284960697, 21352.0, 23267.76732232656, 23353.41616459602, 37921.2415751689, 37982.0, 38016.809915950245, 38022.0, 38135.809320953216, 439200.80399597797, 439897.0, 440256.0, 442901.0, 443124.0, 31773.523397148598, 32402.189945251557, 32445.51331730073, 32459.837700811346, 32497.675023249463, 40922.59077409307, 41413.58586414097, 42151.73544793661, 42192.0, 42214.0, 27613.30966725848, 28374.716252837206, 29398.853005734836, 29603.48370388455, 29787.255318617208, 28470.43059138847, 29492.0, 29731.851340743156, 29775.255618609706, 29843.32330017751, 30931.69068309378, 35230.47154292637, 35330.823345884135, 35448.759293424606, 35467.645323546436, 26503.469930601666, 28398.43203135967, 28620.57069143923, 30076.699233008265, 30596.541051884684, 21838.672419914034, 23691.52616947686, 23915.641265380684, 24036.759632403453, 24081.638775418032], "value": 17212.905829639258, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.4218671992012, 423.2100336447157, 433.6249144490016, 436.7292278787152, 450.33575476172626, 17241.46549957259, 21555.353339399822, 22385.328440146823, 23425.648615270784, 23437.296881093553, 22805.657915131287, 23043.42391440215, 23565.646515302284, 23740.76259237407, 24062.917168727407, 5685.0, 5951.196588460546, 6101.169649105273, 6264.498840092804, 6328.9683551582175, 6137.110119032737, 6248.31268560458, 6252.562320637558, 6262.812115636538, 6263.1544741459775, 6024.246969128859, 6113.358097399776, 6132.938670613304, 6235.594686345373, 6236.376362363772, 5252.36971563411, 5307.893842123136, 6132.386761323876, 6196.318404975472, 6207.844803879894, 5521.69630670317, 5539.279893613804, 5546.91679624806, 5556.027695153332, 5560.7219639017885, 27150.5927411089, 28127.578086328722, 28150.0, 28270.869165233365, 28610.14169574916, 2213.435573928654, 2235.564065007324, 2256.9887150564305, 2257.762934891829, 2260.1185537640335, 17832.821671783247, 18028.27886884523, 18317.3588924387, 18338.541536461562, 18608.534786630305, 2085.4265077103764, 2112.408525612831, 2114.0909408954144, 2142.046789178816, 2147.3665268745603, 146737.2663136688, 147034.0, 149224.2538787299, 149319.0, 151218.48781512154, 10614.575416983389, 11111.33324003904, 11822.822657660143, 12444.937775311066, 12492.875071249171, 13971.510997115052, 13987.650308742159, 15911.204439777965, 16578.336866525446, 16600.335986560644, 8783.604737786687, 8830.42602230853, 8946.507942063248, 9094.181523662903, 9113.863292050624, 7019.92980070203, 8534.588722399434, 8563.972323321117, 8772.473651580925, 8786.780330491662, 7520.887186692204, 7768.8725441208535, 7772.261635144652, 7775.0, 7784.338331241795, 1815.0, 2130.637791575436, 2132.7227460430354, 2134.2956824247963, 2139.8074173324476, 869.9826003480022, 901.6123067081218, 925.5141050948172, 938.9953050234706, 947.1428357336621, 19041.333553325843, 20954.37136885896, 21024.264150754956, 21138.47153821136, 21143.894280528806, 965.710286913923, 969.9078587534173, 1003.5734812704571, 1004.2417974429259, 1004.382304882492, 10378.688639340831, 10981.40640290789, 11008.449577521253, 11100.888991110147, 11117.888821111846, 600176.0, 610839.0, 614643.0, 700774.0, 704632.0, 142133.0, 143535.0, 144544.0, 145339.27330363492, 146393.0, 1181.8345431639577, 1199.580146948561, 1224.9938750306192, 1230.2618428942742, 1232.0328542094412, 15113.848861511244, 18481.0, 18502.167402467192, 18555.721664174776, 18596.907015464836, 56118.719406402706, 59047.0, 63755.3624463768, 63855.680721596094, 64653.70692586028, 80550.0, 81780.59109704413, 84163.0, 84770.0, 85554.57222713847, 8430.494170349799, 8617.181367770052, 8697.173768491983, 9207.631694732403, 9210.861837072584, 13889.652758681113, 13985.790213146607, 14271.0, 14299.57101286963, 14496.927515362355, 11754.647360579196, 11811.822822657496, 11984.981276591587, 12160.635180944586, 12263.816042759545, 11182.944085279521, 11288.887111129112, 11306.208565400342, 11334.773304534028, 11716.9454749073, 11918.40407979615, 12156.392180390769, 12355.75288494243, 12407.0223146446, 12435.37823108859, 8382.245597896219, 8669.869951950599, 8910.643574257216, 8965.59654815548, 9271.211946984582, 10649.307794993301, 11646.76706465849, 12087.81868271959, 12120.575779847662, 12201.145919785884], "value": 10502.399976911089, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1334.2328161307248, 1395.7487652222603, 1415.2216281045423, 1415.306499815091, 1417.617243344297, 10939.0, 11453.083753299738, 11453.770924581504, 11462.656120316404, 11469.197156199069, 10884.782304353928, 11234.775304493905, 11336.376499292535, 11347.262427942178, 11352.091832653388, 2472.159465781634, 2508.109621084516, 2510.3724068982756, 2511.7864981476596, 2512.57286261336, 2064.504518915457, 2352.882355882208, 2435.6468312094735, 2485.9254222373356, 2486.813488988324, 2129.765725770164, 2154.407537927075, 2155.7197564316616, 2156.3423155937435, 2157.870527768331, 2129.37183530858, 2348.3542025942916, 2463.679721636194, 2466.0259197616842, 2468.123816045314, 2431.9270421887372, 2466.136852101768, 2491.2401717476164, 2497.2008957133726, 2497.600383938561, 10998.835017474745, 11221.887781122166, 11224.270422422516, 11225.158113141548, 11233.494492747765, 5511.917321240185, 6327.493800495995, 6560.8846496095775, 6562.737490500374, 6569.704363303663, 3917.1186483041374, 4174.666026717855, 4230.851920182809, 4249.235137675249, 4254.489461264637, 1208.7401208740075, 1213.2417239225483, 1249.925004499733, 1263.6019653809021, 1263.9810402843964, 15380.384784608714, 15695.764563531442, 15718.21408929549, 15775.368985240577, 15775.92112039444, 5613.831585052455, 5684.204211410406, 5775.123084997393, 5962.761489540457, 5981.7607295707685, 13214.603561893158, 13260.204387736767, 13264.336783160803, 13273.137246078973, 13275.800862987064, 6594.604323740591, 6596.4393026592315, 6596.934030659728, 6600.0, 6609.339066093352, 5860.150278209636, 5896.970515147397, 6098.298695649968, 6174.25908890936, 6177.1969643946895, 4447.688661793708, 4569.840055598104, 4578.702384345007, 4611.861644150681, 4655.441347038377, 1362.489066600025, 1408.8802451791507, 1409.2530958591963, 1429.6640289531952, 1439.0, 716.9677364518609, 726.596738809955, 752.736542210233, 759.4380158682535, 784.6429874407131, 5583.944160558423, 6735.49483788708, 6963.7562685305775, 6974.442044636518, 7010.579365238102, 475.9619230461555, 570.5920267009109, 578.3926876779449, 584.8070136854827, 594.8839976204686, 3929.7445666031617, 4155.065110350147, 4161.230172418052, 4282.079352939133, 4301.139772045541, 13735.175889446666, 14354.85645143556, 14360.281985900872, 14366.35351409189, 14377.137371757728, 12742.68143296425, 14229.78655320171, 14251.429942802171, 14253.429862805579, 14504.0, 10228.846567301496, 10500.527476263434, 10510.527026283835, 10512.894871051345, 10531.315464494783, 6394.6979087527525, 6715.9434112222025, 6908.274631163656, 6929.1684997800585, 6932.445404367538, 12732.618021459371, 12756.872431275568, 12765.553205637758, 12934.353282336035, 13005.674858128623, 13216.074874758675, 13267.933660331637, 13399.0, 13406.865931340952, 13441.865581344453, 8948.0, 9230.676926307548, 9366.391184573253, 9376.81246375055, 9379.577918993798, 10121.848172277274, 10191.94904025475, 10240.743981400525, 10339.948300258451, 10371.325863818827, 8733.78165545841, 8746.049516034795, 8818.779530511789, 8830.690925817566, 8888.0, 8382.622781974704, 8517.701880434155, 8598.871016934625, 8599.097094805205, 8622.26710729595, 7788.961055194688, 7864.5281283123195, 7872.527648341118, 7968.561729104955, 7978.321842643209, 5809.215755872903, 5945.286565612154, 6073.696315184311, 6178.969105154445, 6190.84215484532, 5372.221027950847, 5787.276590426197, 5920.230370051866, 6141.508679305734, 6158.168647232566], "value": 5251.327727385209, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.1608171817706, 389.9629535194157, 404.47418356137024, 404.8744889084386, 409.25719818529365, 5600.523955463786, 5887.293524777027, 5915.674637894914, 6075.969620151898, 6087.421694938985, 4451.421315229016, 5112.84960883801, 5869.207656966309, 5881.705914704269, 5907.232059832227, 3674.0998455378403, 4031.1333063391367, 4424.911501769962, 4427.2916333386665, 4449.644028477714, 4200.2617670273485, 4443.0, 4445.377647129389, 4463.553644635543, 4468.19572476954, 4354.97822510887, 4363.847265345714, 4374.321980093091, 4393.934090988638, 4422.270325396322, 3599.3341231872055, 4345.326474396458, 4356.259435895905, 4361.323994780814, 4362.650987920959, 3772.6604605585353, 3785.280796648628, 3787.394016957301, 3788.3370410178386, 3789.9258297330202, 4689.953100468986, 5013.6490445668815, 5363.846772943797, 5432.130859062531, 5804.390538993429, 4513.300438432048, 4962.0324036812835, 5196.454372290893, 5200.867644265871, 5207.661502002357, 5898.911516327258, 5948.910766338508, 5949.613275137145, 6331.810045698637, 6357.904631430532, 4244.87265382039, 4291.206126866539, 4303.376010478494, 4308.3968244445805, 4315.978420107911, 5563.554915606742, 6380.776672816475, 6552.9344706553275, 6704.5306828522025, 6708.597484150967, 3722.9813850930573, 4009.278329900646, 4259.637930775857, 4308.870733877989, 4347.043650396908, 3907.651860108258, 4426.247537918562, 4453.0, 4455.4876189238, 4465.26323156682, 4985.634696579867, 5167.483251674803, 5194.986977539419, 5221.765020574083, 5244.423113457517, 5206.583473322125, 6288.308286088527, 6352.460040896575, 6396.78461092391, 6407.647579383085, 1340.6983428728458, 1408.936597853099, 1479.977800332996, 1512.6067222522004, 1516.5753588995099, 810.3881569415172, 976.5166242709904, 984.1093810101869, 1003.9698809035741, 1007.4861820471466, 5348.3849357323625, 5806.187133801272, 5866.0, 5911.379305172895, 6026.282509484776, 6434.356564343622, 6502.187226596676, 6518.6636739468295, 6542.967285163544, 6627.9337206627315, 4913.754312284372, 5537.252470916455, 5695.5443564514735, 5916.7337469813965, 5945.464908158287, 2527.0397249045245, 3215.4694475411293, 3397.8470968806464, 3460.1349662584353, 3461.3596484649916, 6469.838254043687, 6500.577462464925, 6697.866042679119, 6777.72889084441, 6819.454443644497, 5606.411326810708, 5894.764209431661, 6743.089682892844, 6767.898481522781, 6771.645670865846, 6539.640319782363, 6797.592144471348, 6807.319268073153, 6827.453803695693, 6827.863442731117, 5983.80323935206, 6343.082182196242, 6455.160829092188, 6509.446697030616, 6536.738530458729, 5922.348541660328, 6453.386928241809, 6488.0, 6546.247181573989, 6676.49926255523, 5248.6588371755715, 5752.993226185485, 6379.872402552016, 6544.8363790905605, 6779.932120703035, 4312.310030395247, 4321.438213032286, 4352.563653994111, 4376.1904047749995, 4376.474823021258, 5547.833564993057, 5926.992411290004, 6129.141920131275, 6660.933390666031, 6708.048458678166, 5383.892322153457, 6357.841053973688, 6390.7443702251385, 6405.231372235362, 6413.102165696713, 5415.804675241627, 6056.727447264796, 6352.364763523796, 6373.6175829450385, 6412.583182093149, 5867.882642347215, 5944.4352786485215, 6203.410675985774, 6284.151639528605, 6291.620664332113, 6329.177206963065, 6403.911335073142, 6420.518461115342, 6422.775202867877, 6426.518011149276, 4894.853154405374, 5924.733386997682, 6215.321863097029, 6227.252729672468, 6291.496680265659], "value": 4588.738605258039, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.189244261542, 789.124072279769, 827.3422629009934, 828.6519661742068, 842.4776638484141, 15437.0, 15772.842271577281, 16174.353025878965, 16935.153242337863, 17039.0, 12967.675808104803, 14922.253887305646, 15162.393504259844, 15518.224088795572, 15702.528924132293, 947.1996163242084, 965.512416229803, 967.2793768642372, 969.1132613658494, 970.1656575345197, 919.776696993001, 964.1563631822156, 982.4743762087285, 984.1437948984394, 1007.2143727892219, 1058.751193469534, 1062.9468526573678, 1064.5794911010184, 1065.0467831291028, 1065.275612583447, 1056.1761825775907, 1059.417320473744, 1060.2631171335925, 1060.6287799270272, 1060.8833028366912, 783.2402569507568, 784.2392878907451, 784.4038530716697, 785.6346798738614, 785.6464590934103, 16310.266038028318, 16680.165991700487, 16778.412755553498, 16811.915940420342, 17119.400820971332, 12228.938855305756, 14778.0, 14894.776578351333, 15031.774523382159, 15795.289211985373, 13025.86974130256, 13303.268320242383, 13440.529581464602, 13572.117812342167, 13572.864271357259, 3373.089265898194, 3378.847951842173, 3390.3897298486263, 3404.0, 3413.4367829308135, 108088.45955770249, 108815.82368352589, 110268.89731102747, 110778.89221107766, 110946.0, 11556.711082223012, 11564.363959982113, 11667.474963626657, 11874.584389546324, 12070.818937715942, 23445.296641100795, 24083.0, 24703.87648061748, 24771.87614061954, 24780.628290575656, 8041.396895232764, 8051.798705032304, 8057.395695322874, 8151.836963260702, 8168.346532277523, 3619.2761447710564, 4132.938005929914, 4136.152088821785, 4202.516710578323, 4228.616429002173, 4108.876733697994, 4203.810828512725, 4217.240896638573, 4431.446069241345, 4616.076784643018, 815.8980127484065, 915.0529202275612, 941.1717688434134, 944.3106532231509, 945.1588086602928, 608.3308360803081, 615.7198474694003, 619.6498978077371, 641.7016087519326, 663.1875951958908, 8245.432472236611, 10106.242031847642, 10268.229882758824, 10310.639127630497, 10485.475726213812, 598.931122920861, 611.1993288791608, 614.1954040207343, 627.4541149200224, 661.2065521374274, 4454.398656181439, 4517.864464066083, 4730.834420795324, 4735.171345014609, 4782.139214941344, 93964.53017735004, 95991.0, 97388.0, 97460.51269743606, 99129.0, 84343.0, 86079.13920860837, 86878.56560717156, 87025.0, 87268.0, 73017.6349118251, 77583.0, 77638.61180694061, 78228.0, 78639.21360786434, 9430.245580353418, 9573.377730447499, 9586.089321514444, 9592.376495528048, 9604.75988100303, 24499.632505512076, 39554.60445395509, 40278.59721402748, 40392.596074038884, 40666.59333406628, 50441.73895652638, 50974.49025509697, 51181.62592927838, 52174.0, 64772.67613661902, 13509.662258443617, 13688.45246190181, 13810.309484525935, 14050.826592505146, 14584.48954286595, 14996.250187490363, 15098.471553495576, 15221.31504082341, 15458.381664733284, 15554.9222253888, 13369.732605347644, 13815.240161791198, 13996.0, 14193.889406942393, 14634.780478293047, 11732.70668233301, 11874.168808183344, 12597.559085431965, 12905.612831614691, 14856.405743770129, 13346.866531334563, 13369.866301336862, 13458.664164022535, 13515.797263040866, 13887.04516410261, 10025.899741002497, 10227.795444090927, 10357.689269321932, 10830.187735919995, 11199.328040317607, 10145.695629130843, 11066.225364224427, 11073.944630276797, 11299.43502824839, 11334.263272887236], "value": 7732.862262763583, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1632.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 122.0, 124.0, 123.0, 124.0, 124.0, 124.0, 121.0, 124.0, 123.0], "value": 122.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 117.0, 118.0, 165.0, 118.0, 121.0, 120.0, 119.0, 146.0, 120.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [214.0, 223.0, 214.0, 222.0, 212.0, 210.0, 209.0, 211.0, 245.0, 302.0], "value": 226.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 65.0, 66.0, 102.0, 66.0, 64.0, 63.0, 61.0, 84.0, 64.0], "value": 72.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 77.0, 96.0, 76.0, 76.0, 74.0, 91.0, 73.0, 74.0, 92.0], "value": 82.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 91.0, 91.0, 90.0, 91.0, 91.0, 106.0, 139.0, 90.0], "value": 97.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 107.0, 156.0, 101.0, 100.0, 99.0, 102.0, 101.0, 101.0, 100.0], "value": 106.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 102.0, 100.0, 100.0, 101.0, 102.0, 102.0, 101.0, 102.0, 102.0], "value": 101.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 51.0, 51.0, 52.0, 53.0, 52.0, 54.0, 52.0, 54.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 65.0, 73.0, 68.0, 67.0, 65.0, 64.0, 62.0, 64.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 94.0, 110.0, 117.0, 118.0, 94.0, 92.0, 89.0, 89.0], "value": 98.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 167.0, 170.0, 156.0, 148.0, 152.0, 172.0, 177.0, 218.0, 206.0], "value": 172.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 214.0, 228.0, 249.0, 249.0, 208.0, 210.0, 222.0, 186.0, 251.0], "value": 221.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 80.0, 98.0, 105.0, 101.0, 83.0, 72.0, 73.0, 73.0], "value": 83.1, "unit": "score"}]}]} 14:48:04 INFO - Return code: 0 14:48:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:48:04 ERROR - # TBPL SUCCESS # 14:48:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:48:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:48:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:48:04 INFO - 2017-04-05 14:48:04.657 screenresolution[1991:21078] starting screenresolution argv=screenresolution get 14:48:04 INFO - 2017-04-05 14:48:04.660 screenresolution[1991:21078] Display 0: 1600x1200x32@60 14:48:04 INFO - 2017-04-05 14:48:04.666 screenresolution[1992:21081] starting screenresolution argv=screenresolution list 14:48:04 INFO - Available Modes on Display 0 14:48:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:48:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:48:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:48:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:48:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:48:04 INFO - Intel Iris: 14:48:04 INFO - Chipset Model: Intel Iris 14:48:04 INFO - Type: GPU 14:48:04 INFO - Bus: Built-In 14:48:04 INFO - VRAM (Dynamic, Max): 1536 MB 14:48:04 INFO - Vendor: Intel (0x8086) 14:48:04 INFO - Device ID: 0x0a2e 14:48:04 INFO - Revision ID: 0x0009 14:48:04 INFO - Displays: 14:48:04 INFO - Display: 14:48:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:48:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:48:04 INFO - Display Serial Number: 1600x1200 60 14:48:04 INFO - Main Display: Yes 14:48:04 INFO - Mirror: Off 14:48:04 INFO - Online: Yes 14:48:04 INFO - Rotation: Supported 14:48:04 INFO - Return code: 0 14:48:04 INFO - Running post-action listener: _resource_record_post_action 14:48:04 INFO - [mozharness: 2017-04-05 21:48:04.899301Z] Finished run-tests step (success) 14:48:04 INFO - Running post-run listener: _resource_record_post_run 14:48:04 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 692224; Write bytes: 512062464; Read time: 6; Write time: 1534 14:48:04 INFO - TinderboxPrint: CPU usage
53.5% 14:48:04 INFO - TinderboxPrint: I/O read bytes / time
692,224 / 6 14:48:04 INFO - TinderboxPrint: I/O write bytes / time
512,062,464 / 1,534 14:48:04 INFO - TinderboxPrint: CPU idle
2,244.5 (46.5%) 14:48:04 INFO - TinderboxPrint: CPU system
965.6 (20.0%) 14:48:04 INFO - TinderboxPrint: CPU user
1,615.6 (33.5%) 14:48:04 INFO - TinderboxPrint: Swap in / out
206,991,360 / 0 14:48:04 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207952384; Write bytes: 207998976; Read time: 15974; Write time: 401 14:48:04 INFO - run-tests - Wall time: 1187s; CPU: 53.0%; Read bytes: 651264; Write bytes: 302695424; Read time: 6; Write time: 1127 14:48:05 INFO - Running post-run listener: _upload_blobber_files 14:48:05 INFO - Blob upload gear active. 14:48:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:48:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:48:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:48:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:48:05 INFO - (blobuploader) - INFO - Open directory for files ... 14:48:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:48:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:48:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:48:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:48:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:48:06 INFO - (blobuploader) - INFO - Done attempting. 14:48:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:48:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:48:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:48:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:48:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:48:07 INFO - (blobuploader) - INFO - Done attempting. 14:48:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:48:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:48:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:48:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:48:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:48:08 INFO - (blobuploader) - INFO - Done attempting. 14:48:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:48:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:48:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:48:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:48:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:48:10 INFO - (blobuploader) - INFO - Done attempting. 14:48:10 INFO - (blobuploader) - INFO - Iteration through files over. 14:48:10 INFO - Return code: 0 14:48:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:48:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:48:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb896d9e48eea97d42082e9e32773ee0fbe373f1117b041227a6a4f0a0775a670a5ac341d76d1d0d488ec9e6d674caf24fdb6aa7204d31bd17fc5c200d3a2adc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bdf0be522c63f130d7345ddca7868df54f70d00959397fc0a37ad74ea1b502c65ac97de731287358236b82293a6bfb10ba1839f1f7c0156cec338cc465310e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1b9868e427ba038505cbe9268acba0e1050e693f514f0ce2554e12a78bfc53fb3ad5d88e6736123176913b27e91952322f5bc1320de71cc7944015742dc1d84", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d11ce0cc9ff6aba9236c841857a084a98c67dcd82a6a88d58c3864286a3e57c20e813aa210e54e51e5e0ea0b77dc0e4c6e9fcdbda4d0f5304c9c3a28f0e0dd5e"} 14:48:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:48:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:48:10 INFO - Contents: 14:48:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb896d9e48eea97d42082e9e32773ee0fbe373f1117b041227a6a4f0a0775a670a5ac341d76d1d0d488ec9e6d674caf24fdb6aa7204d31bd17fc5c200d3a2adc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bdf0be522c63f130d7345ddca7868df54f70d00959397fc0a37ad74ea1b502c65ac97de731287358236b82293a6bfb10ba1839f1f7c0156cec338cc465310e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1b9868e427ba038505cbe9268acba0e1050e693f514f0ce2554e12a78bfc53fb3ad5d88e6736123176913b27e91952322f5bc1320de71cc7944015742dc1d84", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d11ce0cc9ff6aba9236c841857a084a98c67dcd82a6a88d58c3864286a3e57c20e813aa210e54e51e5e0ea0b77dc0e4c6e9fcdbda4d0f5304c9c3a28f0e0dd5e"} 14:48:10 INFO - Running post-run listener: copy_logs_to_upload_dir 14:48:10 INFO - Copying logs to upload dir... 14:48:10 INFO - mkdir: /builds/slave/test/build/upload/logs 14:48:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.514521 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-04-05 14:48:10.745543) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-05 14:48:10.751970) ========= 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.lkxRJMatoR/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.WlSeK2g2v1/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb896d9e48eea97d42082e9e32773ee0fbe373f1117b041227a6a4f0a0775a670a5ac341d76d1d0d488ec9e6d674caf24fdb6aa7204d31bd17fc5c200d3a2adc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bdf0be522c63f130d7345ddca7868df54f70d00959397fc0a37ad74ea1b502c65ac97de731287358236b82293a6bfb10ba1839f1f7c0156cec338cc465310e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1b9868e427ba038505cbe9268acba0e1050e693f514f0ce2554e12a78bfc53fb3ad5d88e6736123176913b27e91952322f5bc1320de71cc7944015742dc1d84", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d11ce0cc9ff6aba9236c841857a084a98c67dcd82a6a88d58c3864286a3e57c20e813aa210e54e51e5e0ea0b77dc0e4c6e9fcdbda4d0f5304c9c3a28f0e0dd5e"} build_url:https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011492 build_url: 'https://queue.taskcluster.net/v1/task/XfHkKcCZQqijvkwhlFv4Mw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb896d9e48eea97d42082e9e32773ee0fbe373f1117b041227a6a4f0a0775a670a5ac341d76d1d0d488ec9e6d674caf24fdb6aa7204d31bd17fc5c200d3a2adc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bdf0be522c63f130d7345ddca7868df54f70d00959397fc0a37ad74ea1b502c65ac97de731287358236b82293a6bfb10ba1839f1f7c0156cec338cc465310e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1b9868e427ba038505cbe9268acba0e1050e693f514f0ce2554e12a78bfc53fb3ad5d88e6736123176913b27e91952322f5bc1320de71cc7944015742dc1d84", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d11ce0cc9ff6aba9236c841857a084a98c67dcd82a6a88d58c3864286a3e57c20e813aa210e54e51e5e0ea0b77dc0e4c6e9fcdbda4d0f5304c9c3a28f0e0dd5e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-05 14:48:10.792019) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 14:48:10.792541) ========= 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.lkxRJMatoR/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.WlSeK2g2v1/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.005380 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 14:48:10.843109) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-05 14:48:10.843598) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-05 14:48:10.844157) ========= ========= Total master_lag: 0.20 =========