builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0227 starttime: 1489752960.64 results: success (0) buildid: 20170317030204 builduid: ee8031e45e1e4344b996c7dd52d2c71b revision: 39607304b774591fa6e32c4b06158d869483c312 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-17 05:16:00.644524) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-17 05:16:00.644967) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-17 05:16:00.645284) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-17 05:16:00.671951) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-17 05:16:00.672363) ========= 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.aDJ06KSdmg/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.jt4oiGOnV6/Listeners TMPDIR=/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-17 05:16:00-- 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% 25.9M=0s 2017-03-17 05:16:01 (25.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.222626 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-17 05:16:01.919517) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 05:16:01.919822) ========= 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.aDJ06KSdmg/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.jt4oiGOnV6/Listeners TMPDIR=/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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.027095 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 05:16:01.970389) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-17 05:16:01.970789) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 39607304b774591fa6e32c4b06158d869483c312 --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 39607304b774591fa6e32c4b06158d869483c312 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aDJ06KSdmg/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.jt4oiGOnV6/Listeners TMPDIR=/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-17 05:16:02,097 truncating revision to first 12 chars 2017-03-17 05:16:02,097 Setting DEBUG logging. 2017-03-17 05:16:02,097 attempt 1/10 2017-03-17 05:16:02,097 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/39607304b774?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-17 05:16:03,080 unpacking tar archive at: mozilla-central-39607304b774/testing/mozharness/ program finished with exit code 0 elapsedTime=1.264478 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-17 05:16:03.259031) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-17 05:16:03.259381) ========= script_repo_revision: 39607304b774591fa6e32c4b06158d869483c312 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-17 05:16:03.259794) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-17 05:16:03.260128) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-17 05:16:03.280310) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 43 secs) (at 2017-03-17 05:16:03.280715) ========= /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.aDJ06KSdmg/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.jt4oiGOnV6/Listeners TMPDIR=/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 05:16:03 INFO - MultiFileLogger online at 20170317 05:16:03 in /builds/slave/test 05:16:03 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 05:16:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:16:03 INFO - {'append_to_log': False, 05:16:03 INFO - 'base_work_dir': '/builds/slave/test', 05:16:03 INFO - 'blob_upload_branch': 'Firefox', 05:16:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:16:03 INFO - 'branch': 'Firefox', 05:16:03 INFO - 'buildbot_json_path': 'buildprops.json', 05:16:03 INFO - 'config_files': ('talos/mac_config.py',), 05:16:03 INFO - 'default_actions': ('clobber', 05:16:03 INFO - 'read-buildbot-config', 05:16:03 INFO - 'download-and-extract', 05:16:03 INFO - 'populate-webroot', 05:16:03 INFO - 'create-virtualenv', 05:16:03 INFO - 'install', 05:16:03 INFO - 'run-tests'), 05:16:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:16:03 INFO - 'download_minidump_stackwalk': True, 05:16:03 INFO - 'download_symbols': 'ondemand', 05:16:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:16:03 INFO - 'tooltool.py': '/tools/tooltool.py', 05:16:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:16:03 INFO - '/tools/misc-python/virtualenv.py')}, 05:16:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:16:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:16:03 INFO - 'gecko_profile': False, 05:16:03 INFO - 'gecko_profile_interval': 0, 05:16:03 INFO - 'installer_path': 'installer.exe', 05:16:03 INFO - 'log_level': 'info', 05:16:03 INFO - 'log_name': 'talos', 05:16:03 INFO - 'log_to_console': True, 05:16:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:16:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:16:03 INFO - 'opt_config_files': (), 05:16:03 INFO - 'pip_index': False, 05:16:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:16:03 INFO - 'cmd': ('bash', 05:16:03 INFO - '-c', 05:16:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:16:03 INFO - 'enabled': True, 05:16:03 INFO - 'halt_on_failure': False, 05:16:03 INFO - 'name': 'check_screen_resolution'},), 05:16:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:16:03 INFO - 'cmd': ('bash', 05:16:03 INFO - '-c', 05:16:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:16:03 INFO - 'enabled': True, 05:16:03 INFO - 'halt_on_failure': False, 05:16:03 INFO - 'name': 'check_screen_resolution'},), 05:16:03 INFO - 'run_cmd_checks_enabled': True, 05:16:03 INFO - 'suite': 'dromaeojs', 05:16:03 INFO - 'system_bits': '32', 05:16:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:16:03 INFO - 'title': 't-yosemite-r7-0227', 05:16:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:16:03 INFO - 'use_talos_json': True, 05:16:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:16:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:16:03 INFO - 'work_dir': 'build'} 05:16:03 INFO - [mozharness: 2017-03-17 12:16:03.513722Z] Running clobber step. 05:16:03 INFO - Running pre-action listener: _resource_record_pre_action 05:16:03 INFO - Running main action method: clobber 05:16:03 INFO - rmtree: /builds/slave/test/build 05:16:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:16:07 INFO - Running post-action listener: _resource_record_post_action 05:16:07 INFO - [mozharness: 2017-03-17 12:16:07.887203Z] Finished clobber step (success) 05:16:07 INFO - [mozharness: 2017-03-17 12:16:07.887305Z] Running read-buildbot-config step. 05:16:07 INFO - Running pre-action listener: _resource_record_pre_action 05:16:07 INFO - Running main action method: read_buildbot_config 05:16:07 INFO - Using buildbot properties: 05:16:07 INFO - { 05:16:07 INFO - "project": "", 05:16:07 INFO - "product": "firefox", 05:16:07 INFO - "script_repo_revision": "production", 05:16:07 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:16:07 INFO - "repository": "", 05:16:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:16:07 INFO - "buildid": "20170317030204", 05:16:07 INFO - "stage_platform": "macosx64", 05:16:07 INFO - "basedir": "/builds/slave/test", 05:16:07 INFO - "nightly_build": "True", 05:16:07 INFO - "buildnumber": 72, 05:16:07 INFO - "slavename": "t-yosemite-r7-0227", 05:16:07 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:16:07 INFO - "platform": "yosemite_r7", 05:16:07 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:16:07 INFO - "branch": "mozilla-central", 05:16:07 INFO - "repo_path": "mozilla-central", 05:16:07 INFO - "revision": "39607304b774591fa6e32c4b06158d869483c312", 05:16:07 INFO - "pgo_build": "False", 05:16:07 INFO - "builduid": "ee8031e45e1e4344b996c7dd52d2c71b", 05:16:07 INFO - "slavebuilddir": "test" 05:16:07 INFO - } 05:16:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 05:16:07 INFO - Running post-action listener: _resource_record_post_action 05:16:07 INFO - [mozharness: 2017-03-17 12:16:07.890930Z] Finished read-buildbot-config step (success) 05:16:07 INFO - [mozharness: 2017-03-17 12:16:07.891060Z] Running download-and-extract step. 05:16:07 INFO - Running pre-action listener: _resource_record_pre_action 05:16:07 INFO - Running main action method: download_and_extract 05:16:07 INFO - mkdir: /builds/slave/test/build/tests 05:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:07 INFO - trying https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:16:07 INFO - Downloading https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/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 05:16:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/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 05:16:09 INFO - Downloaded 1325 bytes. 05:16:09 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:16:09 INFO - Using the following test package requirements: 05:16:09 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:16:09 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:16:09 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:16:09 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:16:09 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:16:09 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:16:09 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:16:09 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:16:09 INFO - u'jsshell-mac.zip'], 05:16:09 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:16:09 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:16:09 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:16:09 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:16:09 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:16:09 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:16:09 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:16:09 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:16:09 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:16:09 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:16:09 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:16:09 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:16:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:16:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:16:09 INFO - Fetch https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:16:10 INFO - Expected file size: 52473560 05:16:10 INFO - Obtained file size: 52473560 05:16:27 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 05:16:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:16:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:16:27 INFO - Fetch https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:16:28 INFO - Expected file size: 52473560 05:16:28 INFO - Obtained file size: 52473560 05:16:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:16:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:16:43 INFO - Fetch https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:16:45 INFO - Expected file size: 14404452 05:16:45 INFO - Obtained file size: 14404452 05:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:45 INFO - trying https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:16:45 INFO - Downloading https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:16:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/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 05:16:47 INFO - Downloaded 75143759 bytes. 05:16:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:16:47 INFO - mkdir: /builds/slave/test/properties 05:16:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:16:47 INFO - Writing to file /builds/slave/test/properties/build_url 05:16:47 INFO - Contents: 05:16:47 INFO - build_url:https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:16:49 INFO - Running post-action listener: _resource_record_post_action 05:16:49 INFO - Running post-action listener: set_extra_try_arguments 05:16:49 INFO - [mozharness: 2017-03-17 12:16:49.747424Z] Finished download-and-extract step (success) 05:16:49 INFO - [mozharness: 2017-03-17 12:16:49.747556Z] Running populate-webroot step. 05:16:49 INFO - Running pre-action listener: _resource_record_pre_action 05:16:49 INFO - Running main action method: populate_webroot 05:16:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:16:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:16:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:16:49 INFO - '--tppagecycles', 05:16:49 INFO - '7'], 05:16:49 INFO - 'tests': ['tsvgm']}}, 05:16:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:16:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:16:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:16:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:16:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:16:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:16:49 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:16:49 INFO - 'talos_options': ['--disable-e10s'], 05:16:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:16:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:16:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:16:49 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:16:49 INFO - 'talos_options': ['--disable-e10s'], 05:16:49 INFO - 'tests': ['damp', 'tps']}, 05:16:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:16:49 INFO - 'tests': ['damp', 'tps']}, 05:16:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:16:49 INFO - 'tests': ['dromaeo_dom']}, 05:16:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:16:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:16:49 INFO - 'tests': ['basic_compositor_video']}, 05:16:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:16:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:16:49 INFO - 'tests': ['a11yr', 05:16:49 INFO - 'ts_paint', 05:16:49 INFO - 'tpaint', 05:16:49 INFO - 'sessionrestore', 05:16:49 INFO - 'sessionrestore_no_auto_restore', 05:16:49 INFO - 'tabpaint']}, 05:16:49 INFO - 'other-e10s': {'tests': ['a11yr', 05:16:49 INFO - 'ts_paint', 05:16:49 INFO - 'tpaint', 05:16:49 INFO - 'sessionrestore', 05:16:49 INFO - 'sessionrestore_no_auto_restore', 05:16:49 INFO - 'tabpaint']}, 05:16:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:16:49 INFO - 'ts_paint', 05:16:49 INFO - 'tpaint', 05:16:49 INFO - 'sessionrestore', 05:16:49 INFO - 'sessionrestore_no_auto_restore', 05:16:49 INFO - 'tabpaint']}, 05:16:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:16:49 INFO - 'ts_paint', 05:16:49 INFO - 'tpaint', 05:16:49 INFO - 'sessionrestore', 05:16:49 INFO - 'sessionrestore_no_auto_restore', 05:16:49 INFO - 'tabpaint']}, 05:16:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:16:49 INFO - 'tests': ['a11yr', 05:16:49 INFO - 'ts_paint', 05:16:49 INFO - 'tpaint', 05:16:49 INFO - 'sessionrestore', 05:16:49 INFO - 'sessionrestore_no_auto_restore', 05:16:49 INFO - 'tabpaint']}, 05:16:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:16:49 INFO - 'tests': ['a11yr', 05:16:49 INFO - 'ts_paint', 05:16:49 INFO - 'tpaint', 05:16:49 INFO - 'sessionrestore', 05:16:49 INFO - 'sessionrestore_no_auto_restore', 05:16:49 INFO - 'tabpaint']}, 05:16:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:16:49 INFO - 'tests': ['tsvgx', 05:16:49 INFO - 'tsvgr_opacity', 05:16:49 INFO - 'tart', 05:16:49 INFO - 'tscrollx', 05:16:49 INFO - 'cart', 05:16:49 INFO - 'tsvg_static']}, 05:16:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:16:49 INFO - 'tsvgr_opacity', 05:16:49 INFO - 'tart', 05:16:49 INFO - 'tscrollx', 05:16:49 INFO - 'cart', 05:16:49 INFO - 'tsvg_static']}, 05:16:49 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:16:49 INFO - 'talos_options': ['--disable-e10s'], 05:16:49 INFO - 'tests': ['tp5o']}, 05:16:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:16:49 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:16:49 INFO - 'talos_options': ['--disable-e10s', 05:16:49 INFO - '--xperf_path', 05:16:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:16:49 INFO - 'tests': ['tp5n']}, 05:16:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:16:49 INFO - 'talos_options': ['--xperf_path', 05:16:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:16:49 INFO - 'tests': ['tp5n']}}, 05:16:49 INFO - 'talos.zip': {'path': '', 05:16:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:16:49 INFO - Running post-action listener: _resource_record_post_action 05:16:49 INFO - [mozharness: 2017-03-17 12:16:49.765612Z] Finished populate-webroot step (success) 05:16:49 INFO - [mozharness: 2017-03-17 12:16:49.765722Z] Running create-virtualenv step. 05:16:49 INFO - Running pre-action listener: _resource_record_pre_action 05:16:49 INFO - Running main action method: create_virtualenv 05:16:49 INFO - Creating virtualenv /builds/slave/test/build/venv 05:16:49 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 05:16:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:16:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:16:49 INFO - Using real prefix '/tools/python27' 05:16:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:16:50 INFO - Installing distribute.............................................................................................................................................................................................done. 05:16:53 INFO - Installing pip.................done. 05:16:53 INFO - Return code: 0 05:16:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:16:53 INFO - Reading from file tmpfile_stdout 05:16:53 INFO - Output received: 05:16:53 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) 05:16:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:54 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 0x11053eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110541768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd25730c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031cd30>, '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 0x110243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110311be8>, '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.aDJ06KSdmg/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.jt4oiGOnV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 05:16:54 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 05:16:54 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 05:16:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aDJ06KSdmg/Render', 05:16:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:54 INFO - 'HOME': '/Users/cltbld', 05:16:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:54 INFO - 'LOGNAME': 'cltbld', 05:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:54 INFO - 'MOZ_NO_REMOTE': '1', 05:16:54 INFO - 'NO_EM_RESTART': '1', 05:16:54 INFO - 'PAGER': '/bin/cat', 05:16:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:54 INFO - 'PWD': '/builds/slave/test', 05:16:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:54 INFO - 'SHELL': '/bin/bash', 05:16:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jt4oiGOnV6/Listeners', 05:16:54 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 05:16:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:54 INFO - 'USER': 'cltbld', 05:16:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:54 INFO - 'XPC_FLAGS': '0x0', 05:16:54 INFO - 'XPC_SERVICE_NAME': '0', 05:16:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:54 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 05:16:54 INFO - Cleaning up... 05:16:54 INFO - Return code: 0 05:16:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:55 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 0x11053eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110541768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd25730c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031cd30>, '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 0x110243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110311be8>, '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.aDJ06KSdmg/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.jt4oiGOnV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 05:16:55 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 05:16:55 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 05:16:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aDJ06KSdmg/Render', 05:16:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:55 INFO - 'HOME': '/Users/cltbld', 05:16:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:55 INFO - 'LOGNAME': 'cltbld', 05:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:55 INFO - 'MOZ_NO_REMOTE': '1', 05:16:55 INFO - 'NO_EM_RESTART': '1', 05:16:55 INFO - 'PAGER': '/bin/cat', 05:16:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:55 INFO - 'PWD': '/builds/slave/test', 05:16:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:55 INFO - 'SHELL': '/bin/bash', 05:16:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jt4oiGOnV6/Listeners', 05:16:55 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 05:16:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:55 INFO - 'USER': 'cltbld', 05:16:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:55 INFO - 'XPC_FLAGS': '0x0', 05:16:55 INFO - 'XPC_SERVICE_NAME': '0', 05:16:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:55 INFO - Downloading/unpacking psutil>=3.1.1 05:16:55 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 05:16:55 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 05:16:55 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 05:16:55 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 05:16:55 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 05:16:55 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 05:17:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:17:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:01 INFO - Installing collected packages: psutil 05:17:01 INFO - Running setup.py install for psutil 05:17:01 INFO - building 'psutil._psutil_osx' extension 05:17:01 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 05:17:02 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 05:17:02 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 05:17:02 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 05:17:02 INFO - building 'psutil._psutil_posix' extension 05:17:02 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 05:17:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:17:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:17:02 INFO - ^ 05:17:02 INFO - 1 warning generated. 05:17:02 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 05:17:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:02 INFO - Successfully installed psutil 05:17:02 INFO - Cleaning up... 05:17:02 INFO - Return code: 0 05:17:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:17:02 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')]} 05:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:02 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')]} 05:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:02 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 0x11053eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110541768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd25730c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031cd30>, '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 0x110243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110311be8>, '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.aDJ06KSdmg/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.jt4oiGOnV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 05:17:02 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 05:17:02 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 05:17:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aDJ06KSdmg/Render', 05:17:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:02 INFO - 'HOME': '/Users/cltbld', 05:17:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:02 INFO - 'LOGNAME': 'cltbld', 05:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:02 INFO - 'MOZ_NO_REMOTE': '1', 05:17:02 INFO - 'NO_EM_RESTART': '1', 05:17:02 INFO - 'PAGER': '/bin/cat', 05:17:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:02 INFO - 'PWD': '/builds/slave/test', 05:17:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:02 INFO - 'SHELL': '/bin/bash', 05:17:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jt4oiGOnV6/Listeners', 05:17:02 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 05:17:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:02 INFO - 'USER': 'cltbld', 05:17:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:02 INFO - 'XPC_FLAGS': '0x0', 05:17:02 INFO - 'XPC_SERVICE_NAME': '0', 05:17:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:17:03 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 05:17:03 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 05:17:03 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 05:17:03 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 05:17:03 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 05:17:03 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 05:17:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:17:07 INFO - Installing collected packages: mozsystemmonitor 05:17:07 INFO - Running setup.py install for mozsystemmonitor 05:17:08 INFO - Successfully installed mozsystemmonitor 05:17:08 INFO - Cleaning up... 05:17:08 INFO - Return code: 0 05:17:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:17:08 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')]} 05:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:08 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')]} 05:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:08 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 0x11053eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110541768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd25730c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031cd30>, '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 0x110243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110311be8>, '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.aDJ06KSdmg/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.jt4oiGOnV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 05:17:08 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 05:17:08 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 05:17:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aDJ06KSdmg/Render', 05:17:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:08 INFO - 'HOME': '/Users/cltbld', 05:17:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:08 INFO - 'LOGNAME': 'cltbld', 05:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:08 INFO - 'MOZ_NO_REMOTE': '1', 05:17:08 INFO - 'NO_EM_RESTART': '1', 05:17:08 INFO - 'PAGER': '/bin/cat', 05:17:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:08 INFO - 'PWD': '/builds/slave/test', 05:17:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:08 INFO - 'SHELL': '/bin/bash', 05:17:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jt4oiGOnV6/Listeners', 05:17:08 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 05:17:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:08 INFO - 'USER': 'cltbld', 05:17:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:08 INFO - 'XPC_FLAGS': '0x0', 05:17:08 INFO - 'XPC_SERVICE_NAME': '0', 05:17:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:08 INFO - Downloading/unpacking jsonschema==2.5.1 05:17:08 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 05:17:08 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 05:17:08 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 05:17:08 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 05:17:08 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 05:17:08 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 05:17:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:17:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:17:12 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 05:17:12 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 05:17:12 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 05:17:12 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 05:17:12 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 05:17:12 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 05:17:13 INFO - Downloading functools32-3.2.3-2.tar.gz 05:17:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:17:13 INFO - warning: no files found matching '*.txt' 05:17:13 INFO - no previously-included directories found matching 'build' 05:17:13 INFO - no previously-included directories found matching 'dist' 05:17:13 INFO - no previously-included directories found matching '.git*' 05:17:13 INFO - Installing collected packages: jsonschema, functools32 05:17:13 INFO - Running setup.py install for functools32 05:17:13 INFO - warning: no files found matching '*.txt' 05:17:13 INFO - no previously-included directories found matching 'build' 05:17:13 INFO - no previously-included directories found matching 'dist' 05:17:13 INFO - no previously-included directories found matching '.git*' 05:17:13 INFO - Successfully installed jsonschema functools32 05:17:13 INFO - Cleaning up... 05:17:13 INFO - Return code: 0 05:17:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11053eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110541768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd25730c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031cd30>, '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 0x110243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110311be8>, '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.aDJ06KSdmg/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.jt4oiGOnV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 05:17:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:17:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aDJ06KSdmg/Render', 05:17:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:13 INFO - 'HOME': '/Users/cltbld', 05:17:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:13 INFO - 'LOGNAME': 'cltbld', 05:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:13 INFO - 'MOZ_NO_REMOTE': '1', 05:17:13 INFO - 'NO_EM_RESTART': '1', 05:17:13 INFO - 'PAGER': '/bin/cat', 05:17:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:13 INFO - 'PWD': '/builds/slave/test', 05:17:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:13 INFO - 'SHELL': '/bin/bash', 05:17:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jt4oiGOnV6/Listeners', 05:17:13 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 05:17:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:13 INFO - 'USER': 'cltbld', 05:17:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:13 INFO - 'XPC_FLAGS': '0x0', 05:17:13 INFO - 'XPC_SERVICE_NAME': '0', 05:17:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:17:14 INFO - Cleaning up... 05:17:14 INFO - Return code: 0 05:17:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:17:14 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')]} 05:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:14 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')]} 05:17:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:14 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 0x11053eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110541768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd25730c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031cd30>, '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 0x110243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110311be8>, '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.aDJ06KSdmg/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.jt4oiGOnV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 05:17:14 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 05:17:14 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 05:17:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aDJ06KSdmg/Render', 05:17:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:14 INFO - 'HOME': '/Users/cltbld', 05:17:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:14 INFO - 'LOGNAME': 'cltbld', 05:17:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:14 INFO - 'MOZ_NO_REMOTE': '1', 05:17:14 INFO - 'NO_EM_RESTART': '1', 05:17:14 INFO - 'PAGER': '/bin/cat', 05:17:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:14 INFO - 'PWD': '/builds/slave/test', 05:17:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:14 INFO - 'SHELL': '/bin/bash', 05:17:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jt4oiGOnV6/Listeners', 05:17:14 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 05:17:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:14 INFO - 'USER': 'cltbld', 05:17:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:14 INFO - 'XPC_FLAGS': '0x0', 05:17:14 INFO - 'XPC_SERVICE_NAME': '0', 05:17:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:14 INFO - Downloading/unpacking blobuploader==1.2.4 05:17:14 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 05:17:14 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 05:17:14 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 05:17:14 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 05:17:14 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 05:17:14 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 05:17:18 INFO - Downloading blobuploader-1.2.4.tar.gz 05:17:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:17:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:17:19 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 05:17:19 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 05:17:19 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 05:17:19 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 05:17:19 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 05:17:19 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 05:17:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:17:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:22 INFO - Downloading docopt-0.6.1.tar.gz 05:17:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:17:22 INFO - Installing collected packages: blobuploader, requests, docopt 05:17:22 INFO - Running setup.py install for blobuploader 05:17:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:17:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:17:22 INFO - Running setup.py install for requests 05:17:23 INFO - Running setup.py install for docopt 05:17:23 INFO - Successfully installed blobuploader requests docopt 05:17:23 INFO - Cleaning up... 05:17:23 INFO - Return code: 0 05:17:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:23 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 0x11053eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110541768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd25730c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031cd30>, '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 0x110243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110311be8>, '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.aDJ06KSdmg/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.jt4oiGOnV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 05:17:23 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 05:17:23 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 05:17:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aDJ06KSdmg/Render', 05:17:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:23 INFO - 'HOME': '/Users/cltbld', 05:17:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:23 INFO - 'LOGNAME': 'cltbld', 05:17:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:23 INFO - 'MOZ_NO_REMOTE': '1', 05:17:23 INFO - 'NO_EM_RESTART': '1', 05:17:23 INFO - 'PAGER': '/bin/cat', 05:17:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:23 INFO - 'PWD': '/builds/slave/test', 05:17:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:23 INFO - 'SHELL': '/bin/bash', 05:17:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jt4oiGOnV6/Listeners', 05:17:23 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 05:17:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:23 INFO - 'USER': 'cltbld', 05:17:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:23 INFO - 'XPC_FLAGS': '0x0', 05:17:23 INFO - 'XPC_SERVICE_NAME': '0', 05:17:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:17:23 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-bVXIuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:17:23 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-0G183t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:17:23 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-D24LN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:17:24 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-zqKD2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:17:24 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-9r9kjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:17:24 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-RuOplr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:17:24 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-u7hPY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:17:24 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-LZwBo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:17:24 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-1mfYxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:17:24 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-hvF0Gs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:17:24 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-tM9vwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:17:24 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-Z0vPGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:17:25 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-8WjYHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:17:25 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-w9dWnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:25 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-ifxtxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:17:25 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-ovO3Mj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:17:25 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-ZbJ2vF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:17:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:17:25 INFO - Running setup.py install for manifestparser 05:17:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:17:25 INFO - Running setup.py install for mozcrash 05:17:25 INFO - Running setup.py install for mozdebug 05:17:25 INFO - Running setup.py install for mozdevice 05:17:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:17:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:17:26 INFO - Running setup.py install for mozfile 05:17:26 INFO - Running setup.py install for mozhttpd 05:17:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:17:26 INFO - Running setup.py install for mozinfo 05:17:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:17:26 INFO - Running setup.py install for mozInstall 05:17:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:17:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:17:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:17:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:17:26 INFO - Running setup.py install for mozleak 05:17:26 INFO - Running setup.py install for mozlog 05:17:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:17:27 INFO - Running setup.py install for moznetwork 05:17:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:17:27 INFO - Running setup.py install for mozprocess 05:17:27 INFO - Running setup.py install for mozprofile 05:17:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:17:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:17:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:17:27 INFO - Running setup.py install for mozrunner 05:17:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:17:27 INFO - Running setup.py install for mozscreenshot 05:17:27 INFO - Running setup.py install for moztest 05:17:27 INFO - Running setup.py install for mozversion 05:17:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:17:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:17:28 INFO - Cleaning up... 05:17:28 INFO - Return code: 0 05:17:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17: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')]} 05:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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')]} 05:17:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:28 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 0x11053eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110541768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd25730c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031cd30>, '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 0x110243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110311be8>, '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.aDJ06KSdmg/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.jt4oiGOnV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 05:17:28 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 05:17:28 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 05:17:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aDJ06KSdmg/Render', 05:17:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:28 INFO - 'HOME': '/Users/cltbld', 05:17:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:28 INFO - 'LOGNAME': 'cltbld', 05:17:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:28 INFO - 'MOZ_NO_REMOTE': '1', 05:17:28 INFO - 'NO_EM_RESTART': '1', 05:17:28 INFO - 'PAGER': '/bin/cat', 05:17:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:28 INFO - 'PWD': '/builds/slave/test', 05:17:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:28 INFO - 'SHELL': '/bin/bash', 05:17:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jt4oiGOnV6/Listeners', 05:17:28 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 05:17:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:28 INFO - 'USER': 'cltbld', 05:17:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:28 INFO - 'XPC_FLAGS': '0x0', 05:17:28 INFO - 'XPC_SERVICE_NAME': '0', 05:17:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:17:28 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-HB4ugh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:17:28 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)) 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:17:28 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-JEWNPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:17:28 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)) 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:17:28 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-e7Q54A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:17:28 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)) 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:17:28 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-b0NT4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:17:28 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)) 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:17:28 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-1IlZYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:17:28 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)) 05:17:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:17:28 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-6Gc2tv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:17:29 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)) 05:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:17:29 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-wL4Vwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:17:29 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)) 05:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:17:29 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-5IvDcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:17:29 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)) 05:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:17:29 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-a9y4__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:17:29 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)) 05:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:17:29 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-GDnL07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:17:29 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)) 05:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:17:29 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-A4agEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:17:29 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)) 05:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:17:29 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-vVNXP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:17:29 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)) 05:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:17:29 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-mlEfEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:17:29 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)) 05:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:17:29 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-Dv6gxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:17:29 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)) 05:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:29 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-3mqi7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:29 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)) 05:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:17:29 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-4GeDKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:17:30 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)) 05:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:17:30 INFO - Running setup.py (path:/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/pip-Ec0IBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:17:30 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)) 05:17:30 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)) 05:17:30 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)) 05:17:30 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)) 05:17:30 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)) 05:17:30 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)) 05:17:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:35 INFO - Downloading blessings-1.6.tar.gz 05:17:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:17:35 INFO - Installing collected packages: blessings 05:17:35 INFO - Running setup.py install for blessings 05:17:35 INFO - Successfully installed blessings 05:17:35 INFO - Cleaning up... 05:17:35 INFO - Return code: 0 05:17:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:17:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:17:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:17:35 INFO - Reading from file tmpfile_stdout 05:17:35 INFO - Current package versions: 05:17:35 INFO - blessings == 1.6 05:17:35 INFO - blobuploader == 1.2.4 05:17:35 INFO - docopt == 0.6.1 05:17:35 INFO - functools32 == 3.2.3-2 05:17:35 INFO - jsonschema == 2.5.1 05:17:35 INFO - manifestparser == 1.1 05:17:35 INFO - mozInstall == 1.12 05:17:35 INFO - mozcrash == 1.0 05:17:35 INFO - mozdebug == 0.1 05:17:35 INFO - mozdevice == 0.49 05:17:35 INFO - mozfile == 1.2 05:17:35 INFO - mozhttpd == 0.7 05:17:35 INFO - mozinfo == 0.9 05:17:35 INFO - mozleak == 0.1 05:17:35 INFO - mozlog == 3.4 05:17:35 INFO - moznetwork == 0.27 05:17:35 INFO - mozprocess == 0.25 05:17:35 INFO - mozprofile == 0.28 05:17:35 INFO - mozrunner == 6.13 05:17:35 INFO - mozscreenshot == 0.1 05:17:35 INFO - mozsystemmonitor == 0.3 05:17:35 INFO - moztest == 0.8 05:17:35 INFO - mozversion == 1.4 05:17:35 INFO - psutil == 3.1.1 05:17:35 INFO - requests == 1.2.3 05:17:35 INFO - wsgiref == 0.1.2 05:17:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:35 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 0x11053eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110541768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd25730c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031cd30>, '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 0x110243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110311be8>, '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.aDJ06KSdmg/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.jt4oiGOnV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 05:17:35 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 05:17:35 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 05:17:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aDJ06KSdmg/Render', 05:17:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:35 INFO - 'HOME': '/Users/cltbld', 05:17:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:35 INFO - 'LOGNAME': 'cltbld', 05:17:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:35 INFO - 'MOZ_NO_REMOTE': '1', 05:17:35 INFO - 'NO_EM_RESTART': '1', 05:17:35 INFO - 'PAGER': '/bin/cat', 05:17:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:35 INFO - 'PWD': '/builds/slave/test', 05:17:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:35 INFO - 'SHELL': '/bin/bash', 05:17:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jt4oiGOnV6/Listeners', 05:17:35 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 05:17:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:35 INFO - 'USER': 'cltbld', 05:17:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:35 INFO - 'XPC_FLAGS': '0x0', 05:17:35 INFO - 'XPC_SERVICE_NAME': '0', 05:17:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:36 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)) 05:17:36 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)) 05:17:36 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)) 05:17:36 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)) 05:17:36 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)) 05:17:36 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)) 05:17:36 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)) 05:17:36 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)) 05:17:36 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)) 05:17:36 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)) 05:17:36 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)) 05:17:36 INFO - Cleaning up... 05:17:36 INFO - Return code: 0 05:17:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:17: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')]} 05:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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')]} 05:17:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11053eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110541768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd25730c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11031cd30>, '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 0x110243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110311be8>, '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.aDJ06KSdmg/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.jt4oiGOnV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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 05:17:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:17:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aDJ06KSdmg/Render', 05:17:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:36 INFO - 'HOME': '/Users/cltbld', 05:17:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:36 INFO - 'LOGNAME': 'cltbld', 05:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:36 INFO - 'MOZ_NO_REMOTE': '1', 05:17:36 INFO - 'NO_EM_RESTART': '1', 05:17:36 INFO - 'PAGER': '/bin/cat', 05:17:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:36 INFO - 'PWD': '/builds/slave/test', 05:17:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:36 INFO - 'SHELL': '/bin/bash', 05:17:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jt4oiGOnV6/Listeners', 05:17:36 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 05:17:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:36 INFO - 'USER': 'cltbld', 05:17:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:36 INFO - 'XPC_FLAGS': '0x0', 05:17:36 INFO - 'XPC_SERVICE_NAME': '0', 05:17:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:17:36 INFO - Cleaning up... 05:17:36 INFO - Return code: 0 05:17:36 INFO - Running post-action listener: _resource_record_post_action 05:17:36 INFO - Running post-action listener: _start_resource_monitoring 05:17:36 INFO - Starting resource monitoring. 05:17:36 INFO - [mozharness: 2017-03-17 12:17:36.323865Z] Finished create-virtualenv step (success) 05:17:36 INFO - [mozharness: 2017-03-17 12:17:36.324816Z] Running install step. 05:17:36 INFO - Running pre-action listener: _resource_record_pre_action 05:17:36 INFO - Running main action method: install 05:17:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:17:36 INFO - Reading from file tmpfile_stdout 05:17:36 INFO - Detecting whether we're running mozinstall >=1.0... 05:17:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:17:36 INFO - Reading from file tmpfile_stdout 05:17:36 INFO - Output received: 05:17:36 INFO - Usage: mozinstall [options] installer 05:17:36 INFO - Options: 05:17:36 INFO - -h, --help show this help message and exit 05:17:36 INFO - -d DEST, --destination=DEST 05:17:36 INFO - Directory to install application into. [default: 05:17:36 INFO - "/builds/slave/test"] 05:17:36 INFO - --app=APP Application being installed. [default: firefox] 05:17:36 INFO - mkdir: /builds/slave/test/build/application 05:17:36 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'] 05:17:36 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 05:17:57 INFO - Reading from file tmpfile_stdout 05:17:57 INFO - Output received: 05:17:57 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:17:57 INFO - Running post-action listener: _resource_record_post_action 05:17:57 INFO - [mozharness: 2017-03-17 12:17:57.409817Z] Finished install step (success) 05:17:57 INFO - [mozharness: 2017-03-17 12:17:57.409994Z] Running run-tests step. 05:17:57 INFO - Running pre-action listener: _resource_record_pre_action 05:17:57 INFO - Running main action method: run_tests 05:17:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:17:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:17:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:17:57 INFO - 2017-03-17 05:17:57.424 screenresolution[1626:7606] starting screenresolution argv=screenresolution get 05:17:57 INFO - 2017-03-17 05:17:57.426 screenresolution[1626:7606] Display 0: 1600x1200x32@60 05:17:57 INFO - 2017-03-17 05:17:57.432 screenresolution[1627:7608] starting screenresolution argv=screenresolution list 05:17:57 INFO - Available Modes on Display 0 05:17:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:17:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:17:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:17:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:17:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:17:57 INFO - Intel Iris: 05:17:57 INFO - Chipset Model: Intel Iris 05:17:57 INFO - Type: GPU 05:17:57 INFO - Bus: Built-In 05:17:57 INFO - VRAM (Dynamic, Max): 1536 MB 05:17:57 INFO - Vendor: Intel (0x8086) 05:17:57 INFO - Device ID: 0x0a2e 05:17:57 INFO - Revision ID: 0x0009 05:17:57 INFO - Displays: 05:17:57 INFO - Display: 05:17:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:17:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:17:57 INFO - Display Serial Number: 1600x1200 60 05:17:57 INFO - Main Display: Yes 05:17:57 INFO - Mirror: Off 05:17:57 INFO - Online: Yes 05:17:57 INFO - Rotation: Supported 05:17:57 INFO - Return code: 0 05:17:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:17:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:17:57 INFO - Python 2.7.3 05:17:57 INFO - Return code: 0 05:17:57 INFO - grabbing minidump binary from tooltool 05:17:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:57 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 0x11031cd30>, '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 0x110243db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110311be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:17:57 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 05:17:57 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 05:17:57 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 05:17:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:17:57 INFO - Return code: 0 05:17:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:17:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:17:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:17:57 INFO - ENV: RUST_BACKTRACE is now 1 05:17:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:17:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:17:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0227', '--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 05:17:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0227 --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 05:17:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aDJ06KSdmg/Render', 05:17:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:57 INFO - 'HOME': '/Users/cltbld', 05:17:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:57 INFO - 'LOGNAME': 'cltbld', 05:17:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:17:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:17:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:57 INFO - 'MOZ_NO_REMOTE': '1', 05:17:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:17:57 INFO - 'NO_EM_RESTART': '1', 05:17:57 INFO - 'PAGER': '/bin/cat', 05:17:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:57 INFO - 'PWD': '/builds/slave/test', 05:17:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:17:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:57 INFO - 'RUST_BACKTRACE': '1', 05:17:57 INFO - 'SHELL': '/bin/bash', 05:17:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jt4oiGOnV6/Listeners', 05:17:57 INFO - 'TMPDIR': '/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/', 05:17:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:57 INFO - 'USER': 'cltbld', 05:17:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:57 INFO - 'XPC_FLAGS': '0x0', 05:17:57 INFO - 'XPC_SERVICE_NAME': '0', 05:17:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0227', '--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 05:17:58 INFO - mozversion application_buildid: 20170317030204 05:17:58 INFO - mozversion application_changeset: 39607304b774591fa6e32c4b06158d869483c312 05:17:58 INFO - mozversion application_display_name: Nightly 05:17:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:17:58 INFO - mozversion application_name: Firefox 05:17:58 INFO - mozversion application_remotingname: firefox 05:17:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:17:58 INFO - mozversion application_vendor: Mozilla 05:17:58 INFO - mozversion application_version: 55.0a1 05:17:58 INFO - mozversion platform_buildid: 20170317030204 05:17:58 INFO - mozversion platform_changeset: 39607304b774591fa6e32c4b06158d869483c312 05:17:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:17:58 INFO - mozversion platform_version: 55.0a1 05:17:58 INFO - using testdate: 1489753078 05:17:58 INFO - actual date: 1489753078 05:17:58 INFO - starting webserver on 'localhost:49244' 05:17:58 INFO - SUITE-START | Running 2 tests 05:17:58 INFO - TEST-START | dromaeo_css 05:17:58 INFO - operating with platform_type : mac_ 05:17:58 INFO - Initialising browser for dromaeo_css test... 05:17:58 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/tmpjszexm/profile http://localhost:49244/getInfo.html) 05:18:00 INFO - PID 1637 | __metrics Screen width/height:1600/1200 05:18:00 INFO - PID 1637 | colorDepth:24 05:18:00 INFO - PID 1637 | Browser inner width/height: 1024/689 05:18:00 INFO - PID 1637 | __metrics 05:18:00 INFO - PID 1637 | 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" 05:18:00 INFO - PID 1637 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:18:01 INFO - TEST-INFO | 1637: exit 0 05:18:01 INFO - Browser initialized. 05:18:01 INFO - Running cycle 1/1 for dromaeo_css test... 05:18:01 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/tmpjszexm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:18:13 INFO - PID 1640 | RSS: Main: 233320448 05:18:13 INFO - PID 1640 | 05:21:18 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 05:21:20 INFO - PID 1640 | RSS: Main: 412405760 05:21:20 INFO - PID 1640 | 05:23:03 INFO - PID 1640 | ************************* 05:23:03 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 05:23:03 INFO - PID 1640 | 05:23:03 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:23:03 INFO - PID 1640 | Full stack: 05:23:03 INFO - PID 1640 | ************************* 05:24:23 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 05:24:24 INFO - PID 1640 | RSS: Main: 372322304 05:24:24 INFO - PID 1640 | 05:27:26 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 05:27:27 INFO - PID 1640 | RSS: Main: 358088704 05:27:27 INFO - PID 1640 | 05:30:29 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 05:30:30 INFO - PID 1640 | RSS: Main: 334823424 05:30:30 INFO - PID 1640 | 05:33:34 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 05:33:35 INFO - PID 1640 | RSS: Main: 340942848 05:33:35 INFO - PID 1640 | 05:36:38 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 05:36:38 INFO - PID 1640 | RSS: Main: 420704256 05:36:38 INFO - PID 1640 | 05:36:38 INFO - PID 1640 | __start_tp_report 05:36:38 INFO - PID 1640 | _x_x_mozilla_page_load 05:36:38 INFO - PID 1640 | _x_x_mozilla_page_load_details 05:36:38 INFO - PID 1640 | |i|pagename|runs| 05:36:38 INFO - PID 1640 | |0;dojo.html;3311.7019468247854;3383.6616338366166;3404.6084700259476;3406.0973841931886;3434.1414646338417;5697.173909783079;5777.884442311151;5901.380355062715;5912.70436478176;5934.198883150768;5823.5632327575495;5824.621399609023;5878.7648494060295;5909.054551271797;5959.195508606337;5202.583793296527;5263.552598029162;5279.70961597112;5288.365396152467;5296.046711591912;5347.064263753847;5368.409474957752;5372.677639341632;5420.7018613976215;5456.454354564532;5220.817271395483;5280.313559237313;5283.313169288006;5288.0745869472685;5332.253484512152;5259.921101183485;5288.418273989858;5290.39160496544;5291.497307755757;5297.496737809902;5211.062008838408;5245.213218017289;5248;5255.553277971377;5273.419923808378;5754.741036653319;5759.078547432391;5761.164631128463;5779.2775903012125;5781.450762177628;6253.280872699653;6272.56092073555;6272.968635156841;6275.497960163176;6286.528510361742;4773.95226047742;6272.686365681698;6316.273628532732;6317.810465686037;6322.52581056418;6044.093385992049;6044.123602077718;6045.909311360333;6051.515878729647;6051.636901785863;6228.190335256388;6259.71831267594;6436.324185960407;6438.6136831790245;6451.967740161269;6175.59858609189;6204.224471941007;6245.906311405332;6287.465565426898;6294.370562943669;6324.715387807468;6326.114343991845;6328.303886572473;6346.904796428057;6370.968145159244;5366.302380690485;6298.622082675054;6303.212098487689;6312.400321969406;6331.271903731038;6214.25428948529;6279.152314437492;6287.685615719287;6291.087792270096;6293.276273228637;4949.337684107841;5015.398152221756;5018.5985121190215;5021.221710634857;5039.521245481674;4499.370088187657;4509.481409637933;4540.77296135192;4561.110583436198;4568.840090596813;6059;6106.582013105394;6115.827502811564;6117.877642447126;6124.411449640054;6299.338569450233;6315.242170939425;6318.178636777189;6332.050192471166;6345.49236061114;5851.444112809277;5930.762769489258;5932.2584676915385;6065.120557519223;6090.725917333731;5573.860653483695;5584.5811564132855;5607.299087614048;5611.635243709227;5626.127950167731;6508.381703738138;6525.934740652628;6542.378474044863;6542.443892269211;6556.901646475402;6231.40801623845;6297.779577714758;6304.3380445054445;6307.653079080695;6318.178636777189;6332.366763323631;6430.774922877864;6498.122753428416;6512.511561632991;6532.83667908287;6245.96877015612;6248.437640612185;6273.968630157003;6275.435210831047;6303.747850085945;4894.779734911866;6175.660338681236;6180.721867516063;6245.125682404376;6262.405071518198;5308.734563271898;6075.969620151871;6115.84710382244;6226.875462490815;6260.092286618506;6245.219347581552;6255.906161407674;6262.060690896402;6269.717862696099;6271.43557079865;6102.603330783485;6144.201253836973;6158.445739883432;6161.876762464636;6171.043488259327;6131.938680613137;6167.691615419121;6183.5671502994355;6187.628742275478;6235.033569796688;6115.296740874678;6123.545744966725;6146.385361463817;6152.692365381802;6167.784127555514 05:36:38 INFO - PID 1640 | |1;ext.html;666.7932940788354;705.6013352455863;785.5483097219098;852.2244757270885;864.299917067176;27012.05457808977;27410.31474213143;35948.82025589875;36456.817715911384;36546.8172659137;37517.43723844138;39324.41013384802;39347.40978885319;39362.40956385649;39414.21171576567;8954.32842536809;9151.725448236564;9169.312301577374;9171.862422063641;9174.495402752846;7069.081019467462;7226.5302755321;8921;10513;10741.731456713605;10723.946380268088;10800.081993030602;10845.969632884864;10935.070519005854;10975.2866063706;10808.243422960395;10920.563177472892;10923.836142457869;10926;10959.287646303004;7019.473539484508;7194.064771579661;7194.964025179893;7203.17163526196;10509.527071281735;26005;34804.477932831025;35245.471317929994;35367.64632353669;35377.11557211065;3766.962330376716;3778.622137786227;3801.8859434216743;3830.367989281765;3936.2717897189114;26003.73996260051;26806;27180.184594462196;27713.307167320778;27764.30589235285;3147.134538002045;3147.213196700825;3159.7472202223767;3280.4751239801517;3286.6877646623534;292104;295569;295883.99999999785;295983;296088;46417;46525.53474465212;46566.767166163954;46617;46649.53350466452;49255.50744492509;49266.50733492691;49351.753241234284;49487.752561237685;49489;13952.302384880715;14159.85840141606;14173.433062677585;14174.645633859236;14186.148831070168;11671.241369310968;11703.590374336858;11709.414529273503;11709.531618735326;11727.589534366427;9967.601295948081;10108.595656173818;10127;11435.256708313933;11640.126990475748;5124.743762811844;5464.316960379952;5535.391106978229;5550.7779688812;5562.499375056264;1082.9025387714985;1094.7427354571669;1102.2284400919389;1113.287496002559;1158.8145896656495;26041.224022516628;26214.95140194409;26875.73124268732;26928.59607105895;26947.326316842235;1142.4516232208587;1158.79141754485;1194.9820752688718;1200.9459574319176;1208.6615747590513;13664.590062297948;13782.517611883535;13874.236916969461;13975.510857119953;14105.647358815906;394727.02636487206;395108;396270;396509;396510;46204.8448788783;51122.74438627783;52607.73696131571;53021.469785301655;53109;1410.0904916329116;1434.5767998440695;1436.4110714606961;1438.96402589934;1471.02912078028;25046.874765626057;25927.481450371266;26124.60813087767;26188.607170892832;26350.341241469116;35788;42584.57415425806;42655.7203283902;42681.573184269;42867.57132428636;366099;367921;368837;369568;370604;28923.855380722962;31140.53289200709;31880.88416905397;34057.97826065222;35691.64308356883;45087.774561128295;45142;45538;45756.31365529453;45989.540104599866;36365.45451818278;36976.63023369732;37336.64903949338;38037;38079.80960095182;38118.04704882289;38157.09214539317;38211.61788382192;39237;39261.60738392694;33759.324813504085;38713.20876769654;38735.03162420962;39266.80366598149;39530.802345988086;30975.380492390475;32930;34058.22640291896;34290;35031;22949.311520654406;23424.18015369454;25381.365465863502;25830.87084564565;25843 05:36:38 INFO - PID 1640 | |2;jquery.html;382.0467932508392;396.728241154809;405.2624223912479;411.3336395040035;426.17960426179616;12653.493860245591;13051.347432628367;16904.915475422622;21008.684869726967;21056.368308950718;21678.89160554195;21954.34136975893;22647.886760566173;22745.545089098207;22825.657615135744;6269.968650156743;6367.4269315761685;6496.220453545581;6614.305497922721;6693.933060669404;4105.527864295615;6478.416942475176;6605.801825945229;6608.702608382634;6614.305497922697;4348.760818155;4401.515833258339;7380.520266182708;7384.815379615499;7389.113306403212;7285.235050319693;7340.265973402644;7354.338109570164;7393.778186654409;7424.925750742477;6976.372126508588;7195.532290401159;7197.496175267734;7203.567785932861;7204.56772593646;26245.21264362072;27372.178834634993;27432.725672743218;27519.44961100787;27683.307917302027;2909.229054300612;2920.5765164051095;2933.779966502521;2943.2494713848046;3059.479888418974;16484.34062637493;16558.917205414018;16671.66656666872;16690.33238670452;17646.11769411537;1976.9110390032483;2004.1081718635214;2004.7093171490053;2010.4270282969283;2011.6177926193977;135399;150942;151946;152257;155251;17340.65318693619;20852;20910.895445522674;21202.653456041473;21450.463738406666;28793.99221027254;35127.4730879037;35128.82435587806;35217.83039526455;35338.29323413517;9985.850212246822;10170.033846784543;10378.636747713794;10378.792424151474;10585.788284234271;8377.413581049268;8383.161683831568;8558.058613552505;8579.399442038997;8581.699640512677;9993.550290236957;10311.123554497952;10420.196628230642;10432;10437.947810260901;3389.356022355794;3888.425187798946;3924.293627147141;3926.6466018058513;3945.506811648544;934.4907025671025;986.358866736627;999.1058003087196;1002.1431675916986;1035.7773078787945;22268;22706.43233919165;22727.545449090925;22752.317430477113;22796.4300892479;984.4241118945354;992.4888682328607;1011.1051719228388;1014.3761586624321;1017.8524114003428;10857.565697372016;11522.193446458836;11629.825552616718;11635.709107272385;11654.53381864733;687953;693532;701946;707958;720001;107015.9298407006;110991.1070375777;115598.8440115588;145743;150350;1242.912996090265;1268.9936550317188;1300.6098170548798;1302.5180683147107;1308.1366298243113;20078;21384.823834689243;21399;21423.143074276853;21498.355049348545;50331;50623.931963597875;60867.39132608794;61813.690931545054;62183;86387.56806215929;86557;88144.55927720577;88903.55548222477;89252;11682.299062056303;11716.472758726048;11822.881771182521;12116.575919842764;12154.453049612965;13139.671508212372;13271.203727776365;13576.321183940961;13728.244946528037;14180.645483862574;10956.56173753041;11007;11258.549658013588;11503.367314797766;11557.826632600685;10947.671569852917;11127.443627818415;11341.376224307412;11383.772324553629;11764.82352764692;11471.369074700971;11570.421478925851;11835.763284734428;11851.881481185077;11943.16397852142;11601.82597261025;11661.883381166079;11900.345480998629;11931.940340298243;12062.75874482523;11017.283876548;11088.112950964065;11358.488868000795;11401.828972565583;11552.960233579019 05:36:38 INFO - PID 1640 | |3;mootools.html;1511.8714909232715;1555.906645601264;1572.677601091776;1583.517027306671;1585;10181.185505159589;10623.628173013945;10624;10639.840402393971;10653.84019239712;9340.626374944994;10695.893041069587;10707;10715.892841071587;10717.464126793668;2330.33585428153;2381.404648837791;2382.7259865115475;2384.558856611524;2388.9880550597222;1961.5096225943514;2328.592496313147;2331.265651319829;2333.8249631277636;2350.388898886293;2102.748864425672;2335.5912715274844;2340.719113706358;2343.5898717724417;2345.061975209913;2052.6100040992164;2221.244776775904;2295.598270302699;2304.3087073877937;2314.2247347138727;2199.3401979406112;2302.378357843373;2303.57383883982;2314.340412982292;2321;10776.892231077667;10863.565457381774;10870.61952831647;10898.455077246184;10924.453777311182;5633.40849210831;5860.413958604106;5873.236479257712;5879.353271140214;5879.941200588025;3324.501324801275;3411.4371128763723;3611.295797319524;3622.6920711739535;3631.981840090809;1145.7192987717979;1152.6196355202762;1200.9159358844884;1225.1852518075468;1245.850497940253;13280.937524998073;13772.931135344359;14029.43882244709;14859.331330090172;15031.398744050224;4567.771611419415;4665.183592871234;4693.272542755878;4693.48371679115;4719.197736384823;11258.774824503464;11269;11269.21115521922;11274.323540587591;11283;4854.344663470386;5668.716564171775;5668.773249070074;5673.234113394639;5677.858053548694;4416.734995900192;4600.378948841931;5170;5172.637915345889;5173.818916337986;3642.2715456908722;3714.647108524686;3802.4106263529197;3836.8081595920094;3887.2031233597045;1036.3937096798456;1036.7978244242452;1043.2175868098925;1053.6048981631889;1056.1972900595604;909.3225546967551;943.9811203775886;948.9525523723786;958.2956526952685;984.3454103021554;6360.236771587347;6468.773592924225;6631.3700198481065;6688.4649228061635;6789.083473730983;926.6200857648466;1005.5022763732;1016.5577973581516;1017.9847302290472;1032.2361452525076;4175.665946724254;4178.1643671265865;4185.24665560196;4295.140971805651;4297.505786834491;12661.936690316674;13047;13048.934755326352;13051.347432628521;13070.215787052808;12753.872461275454;12790.744185116431;12927.741445171045;12958.028147888946;13179.868201318055;9735.464549449849;9831.213502919893;9895.554700038516;9908.20734341251;9913.80172396548;6251.218597675291;6388.616682999035;6411.230652321751;6481.578697384655;6503.154589903472;11498.94250528742;11893.821592675944;11905.821412678644;11915.987141093105;11926.403679816149;9883.851742224017;10879.129669626569;10963.780724385628;11634.185607007428;12091.516339346328;7935.32549733256;8197.42618016757;8202.507849529047;8204.54874981858;8269.710560130367;9701.12689857888;9800.215982721507;9822.75443113928;9871.160951319218;9889.851652225367;8614.138586141336;8723.302135829246;8737.100834900608;8925.866112008196;8935;8416.957915210385;8544.359173061921;8568.871466928125;8632.870506942276;8649.221570058726;7541.396688265036;7579.54522728638;7647.197044310267;7653.732119375795;7674.501157424749;5716.5426765859465;5809.244798176379;5819.76720931175;5886.676232807236;5915.37888521699;5238.476152384731;5572.9411411831625;5896.793612223723;5899.882002360015;6031.095335699505 05:36:38 INFO - PID 1640 | |4;prototype.html;353.6039635608119;382.2755877611925;385.652912378859;387.9747816391936;388.1112252940766;5475.67145971242;5876.8236952891375;5887.676177810224;5912;5941.435563621449;4670.346151538787;5562.499375056244;5604.187392828037;5816.738246778895;5857.092150716635;4496.505384407713;4508.188526065307;4522.3668686383935;4530.75080870552;4542.273236282195;4525.162844873693;4533.795979180928;4536.867672194064;4558.236642708378;4560.156371071363;4166.91666166678;4214.852480163195;4484.542523679801;4505.144022635705;4545.068261006502;3926.862559810407;4350.934735978963;4482.036362182114;4488.182286174105;4528.818847246106;3974.8211330489908;3979.3235150024566;3983.860564880244;3988.541317748438;4014.558398576154;5373.408925018245;5675.3757086720425;5775.358337911435;5819.156222347779;5825.38833422493;4923.58149557288;5565.276514053187;5580.888382232373;5596.908602822451;5646.57650676201;5047.722375269359;6260.561760676755;6292.276388215368;6343.936560634381;6361.300256971729;4891.461939186686;4897.975510122462;4924.482929292444;4953.455119936804;4972.5276098770555;6218.533609979269;6410.1987251593555;6447.291467761047;6538.346165383424;6597.571157874723;4816.277558366273;4867.221244600847;4876.878078048077;4892.143874821892;4900.411950565955;4126.711130220856;4642.651801114863;4954.47977962316;5074.746262686851;5148.768305426265;5363.302770639792;5393.6763794172475;5534.806281780118;5579.776808927679;5598.272224610774;5285.656432331886;5531.751071201806;5884.234729581109;6227.353374815685;6232.189815323979;1755.3154269834774;1782.803891571926;1786.1515780004395;1790.5255107396551;1809.0412081596776;1253.655244807667;1281.7628738683372;1300.947962081525;1304.1327517201146;1317.3808310094241;5749.913751293734;5899.852503687356;5936.970315148396;5965.015772397593;5965.671888046112;6060.515158787286;6084.817455476343;6488.578242414228;6531.085648009187;6536;5282.709450980156;5608.551315894719;5905.696778256483;5918.467337939606;5919.289685237713;3159;3362.445196542592;3408.4887266910164;3421.23022319976;3444.4661077533024;6386.712597933104;6727.899081513683;6739.561928474732;6763.864722705518;6805;6195.411435913581;6653.2681405045405;6660.267370589231;6668.699908504129;6680.498962577729;5857.941420585739;6340.9365906340345;6763.323667633295;6770.898436523456;6788.185417749902;6444.677766111582;6520.380563846427;6536.607803531803;6573.244076931214;6576.868462630625;5693.373728889736;6194.132821404917;6476.740930362733;6490.253620833476;6525.0538671893255;6748.628825414649;6806.860820080808;6815.386615204656;6821.317868213139;6831.248562658203;4987.850364489072;5026.969471258456;5092.439831618593;5110.48895110486;5120.539151476385;5763.907625585881;6325.430711236011;6520.771772987922;6559.311272316338;6565.901511477236;5364.731763411892;6082.72627731762;6292.993121100602;6321.747130114928;6387.361263873576;6028.246469191351;6283.780067697791;6338.556301058881;6361.395667411589;6385.584936979268;5238.790448382022;6192.513796688672;6217.502599791916;6220.377962203744;6226.59527130735;6082.087686847009;6343.5876668016435;6360.427561519486;6388.722255548816;6397.712102955285;5809.564282678732;6097.75608975636;6138.8643101026455;6145.84635384119;6151.32335443092 05:36:38 INFO - PID 1640 | |5;yui.html;759.2635143910405;764.6520833020976;823.3084209264217;836.9958150209249;840.1304649687572;14747.705045899076;14786.260686965648;15444.918855680093;15634;15636.218189090529;14360.138391696506;14508;14655;14702.485413010547;14716.558503244893;937.2314701944408;954.0793134625086;957.8275910336137;960.7165886063628;962.1340793286064;933.3233405780788;975.2198241406846;977.0327375897881;988.8467287570438;989.5200827598609;1032.6385764982233;1034;1035.0684384054337;1038.7455073507;1039.776448063666;1026.0509029148009;1026.3431403901473;1027.0705011964185;1029.903153141909;1030.0626429948786;763.8815983522563;765.3341592814231;765.7587859824193;766.4481573267238;766.758471081613;15595.1422671753;15615;15927.681446371007;16408.343666253455;16446.259918303705;14462.421503139862;14663;14669.633259168499;14729.337179826933;14784.260786960716;11553.653390398302;11785.705357366049;11950.940245298805;11953.163278570504;12184.878151218463;3629.4374371972385;3860.7490513116836;3882.165334453078;3882.7282090253693;3905.0042239229097;104263.63935636476;107411.46294268478;107514.46242768737;111951;113929;11085.722856928642;11292.266002709797;11600;11750.236234644892;11844.822327665092;24852.5029499409;25154.24537263885;25192.118275860255;25216.747832521807;25303;7684.269994350528;7789.111929995382;7808.141104478503;7809.804754881174;7958.482698624571;4095.140020595649;4176.202793071457;4183.937240941388;4291.313389857608;4395.758233297135;3418.743594230443;3438.948415773765;3512.631173726773;3518.5074089627474;3522.788632682047;603.0682595390045;611.5657882903099;625.3871206217932;632.5161251642497;632.5666918161074;631.3150231998334;656.297761395312;672.9831754206184;681.9215790184193;692.757534862794;8488.363372746946;9844.458554779556;9941.353812002197;10047.64833230848;10509.84235236472;631.1952260867431;648.7437462202498;665.6704931059156;716.2515171645642;718.5975853522044;4604.516525764813;4847.442544107403;4847.830325938575;4855.465898751135;4859.271109333628;78903.60548197222;83626.58186709028;96794;100117;102734.48632756788;69426.61146777036;70636.64681676662;77501;86766.56616716876;90016.6497502538;61607.6919615399;64457.67771161114;66446.33553664401;69494.65252673705;72224;10548.736281593021;10845;10855.728606784893;11013.614523491638;11087.944560277147;40197.39703904502;44567.55432445634;45672.31491527563;47060.52939470699;48584.01195778792;60011.09983350341;60012.39987600068;60212;61366.38633613606;61558.69220653868;11812.881871181178;13417.322834645669;13651.590452286448;14082.859171408154;14145.575632731034;13305.46778128864;14781.704365912836;14921.925390372979;15043.323050462974;15064.472743454366;13960.720785584435;13975.371108299947;14117.717645646824;14124.364403601658;14847.40610375573;12597.559085432333;13432.731345373233;13990.300484975507;14084.647883802987;14863.405463781328;12845.743085138432;13012.8048079277;13316.533921312708;13890;13939.860601393857;10943;10953;10953.452327383759;11034.944825275823;11938.462769175581;9912.553935073083;11010.174236932102;11118.277311974696;11246.775064498828;11316.660500185008 05:36:38 INFO - PID 1640 | __end_tp_report 05:36:38 INFO - PID 1640 | __start_cc_report 05:36:38 INFO - PID 1640 | _x_x_mozilla_cycle_collect,2063 05:36:38 INFO - PID 1640 | __end_cc_report 05:36:38 INFO - PID 1640 | __startTimestamp1489754198984__endTimestamp 05:36:38 INFO - PID 1640 | 05:36:38 INFO - PID 1640 | ------- Summary: start ------- 05:36:38 INFO - PID 1640 | Number of tests: 6 05:36:38 INFO - PID 1640 | 05:36:38 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:5842.27 Median:6123.55 stddev:659.00 (10.8%) stddev-sans-first:630.41 05:36:39 INFO - PID 1640 | Values: 3311.7 3383.7 3404.6 3406.1 3434.1 5697.2 5777.9 5901.4 5912.7 5934.2 5823.6 5824.6 5878.8 5909.1 5959.2 5202.6 5263.6 5279.7 5288.4 5296.0 5347.1 5368.4 5372.7 5420.7 5456.5 5220.8 5280.3 5283.3 5288.1 5332.3 5259.9 5288.4 5290.4 5291.5 5297.5 5211.1 5245.2 5248.0 5255.6 5273.4 5754.7 5759.1 5761.2 5779.3 5781.5 6253.3 6272.6 6273.0 6275.5 6286.5 4774.0 6272.7 6316.3 6317.8 6322.5 6044.1 6044.1 6045.9 6051.5 6051.6 6228.2 6259.7 6436.3 6438.6 6452.0 6175.6 6204.2 6245.9 6287.5 6294.4 6324.7 6326.1 6328.3 6346.9 6371.0 5366.3 6298.6 6303.2 6312.4 6331.3 6214.3 6279.2 6287.7 6291.1 6293.3 4949.3 5015.4 5018.6 5021.2 5039.5 4499.4 4509.5 4540.8 4561.1 4568.8 6059.0 6106.6 6115.8 6117.9 6124.4 6299.3 6315.2 6318.2 6332.1 6345.5 5851.4 5930.8 5932.3 6065.1 6090.7 5573.9 5584.6 5607.3 5611.6 5626.1 6508.4 6525.9 6542.4 6542.4 6556.9 6231.4 6297.8 6304.3 6307.7 6318.2 6332.4 6430.8 6498.1 6512.5 6532.8 6246.0 6248.4 6274.0 6275.4 6303.7 4894.8 6175.7 6180.7 6245.1 6262.4 5308.7 6076.0 6115.8 6226.9 6260.1 6245.2 6255.9 6262.1 6269.7 6271.4 6102.6 6144.2 6158.4 6161.9 6171.0 6131.9 6167.7 6183.6 6187.6 6235.0 6115.3 6123.5 6146.4 6152.7 6167.8 05:36:39 INFO - PID 1640 | 05:36:39 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:49932.17 Median:35138.24 stddev:93810.06 (267.0%) stddev-sans-first:94000.29 05:36:39 INFO - PID 1640 | Values: 666.8 705.6 785.5 852.2 864.3 27012.1 27410.3 35948.8 36456.8 36546.8 37517.4 39324.4 39347.4 39362.4 39414.2 8954.3 9151.7 9169.3 9171.9 9174.5 7069.1 7226.5 8921.0 10513.0 10741.7 10723.9 10800.1 10846.0 10935.1 10975.3 10808.2 10920.6 10923.8 10926.0 10959.3 7019.5 7194.1 7195.0 7203.2 10509.5 26005.0 34804.5 35245.5 35367.6 35377.1 3767.0 3778.6 3801.9 3830.4 3936.3 26003.7 26806.0 27180.2 27713.3 27764.3 3147.1 3147.2 3159.7 3280.5 3286.7 292104.0 295569.0 295884.0 295983.0 296088.0 46417.0 46525.5 46566.8 46617.0 46649.5 49255.5 49266.5 49351.8 49487.8 49489.0 13952.3 14159.9 14173.4 14174.6 14186.1 11671.2 11703.6 11709.4 11709.5 11727.6 9967.6 10108.6 10127.0 11435.3 11640.1 5124.7 5464.3 5535.4 5550.8 5562.5 1082.9 1094.7 1102.2 1113.3 1158.8 26041.2 26215.0 26875.7 26928.6 26947.3 1142.5 1158.8 1195.0 1200.9 1208.7 13664.6 13782.5 13874.2 13975.5 14105.6 394727.0 395108.0 396270.0 396509.0 396510.0 46204.8 51122.7 52607.7 53021.5 53109.0 1410.1 1434.6 1436.4 1439.0 1471.0 25046.9 25927.5 26124.6 26188.6 26350.3 35788.0 42584.6 42655.7 42681.6 42867.6 366099.0 367921.0 368837.0 369568.0 370604.0 28923.9 31140.5 31880.9 34058.0 35691.6 45087.8 45142.0 45538.0 45756.3 45989.5 36365.5 36976.6 37336.6 38037.0 38079.8 38118.0 38157.1 38211.6 39237.0 39261.6 33759.3 38713.2 38735.0 39266.8 39530.8 30975.4 32930.0 34058.2 34290.0 35031.0 22949.3 23424.2 25381.4 25830.9 25843.0 05:36:39 INFO - PID 1640 | 05:36:39 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:40917.46 Median:21436.80 stddev:116822.26 (545.0%) stddev-sans-first:117110.33 05:36:39 INFO - PID 1640 | Values: 382.0 396.7 405.3 411.3 426.2 12653.5 13051.3 16904.9 21008.7 21056.4 21678.9 21954.3 22647.9 22745.5 22825.7 6270.0 6367.4 6496.2 6614.3 6693.9 4105.5 6478.4 6605.8 6608.7 6614.3 4348.8 4401.5 7380.5 7384.8 7389.1 7285.2 7340.3 7354.3 7393.8 7424.9 6976.4 7195.5 7197.5 7203.6 7204.6 26245.2 27372.2 27432.7 27519.4 27683.3 2909.2 2920.6 2933.8 2943.2 3059.5 16484.3 16558.9 16671.7 16690.3 17646.1 1976.9 2004.1 2004.7 2010.4 2011.6 135399.0 150942.0 151946.0 152257.0 155251.0 17340.7 20852.0 20910.9 21202.7 21450.5 28794.0 35127.5 35128.8 35217.8 35338.3 9985.9 10170.0 10378.6 10378.8 10585.8 8377.4 8383.2 8558.1 8579.4 8581.7 9993.6 10311.1 10420.2 10432.0 10437.9 3389.4 3888.4 3924.3 3926.6 3945.5 934.5 986.4 999.1 1002.1 1035.8 22268.0 22706.4 22727.5 22752.3 22796.4 984.4 992.5 1011.1 1014.4 1017.9 10857.6 11522.2 11629.8 11635.7 11654.5 687953.0 693532.0 701946.0 707958.0 720001.0 107015.9 110991.1 115598.8 145743.0 150350.0 1242.9 1269.0 1300.6 1302.5 1308.1 20078.0 21384.8 21399.0 21423.1 21498.4 50331.0 50623.9 60867.4 61813.7 62183.0 86387.6 86557.0 88144.6 88903.6 89252.0 11682.3 11716.5 11822.9 12116.6 12154.5 13139.7 13271.2 13576.3 13728.2 14180.6 10956.6 11007.0 11258.5 11503.4 11557.8 10947.7 11127.4 11341.4 11383.8 11764.8 11471.4 11570.4 11835.8 11851.9 11943.2 11601.8 11661.9 11900.3 11931.9 12062.8 11017.3 11088.1 11358.5 11401.8 11553.0 05:36:39 INFO - PID 1640 | 05:36:39 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:6475.59 Median:3627.34 stddev:3972.57 (109.5%) stddev-sans-first:3966.21 05:36:39 INFO - PID 1640 | Values: 1511.9 1555.9 1572.7 1583.5 1585.0 10181.2 10623.6 10624.0 10639.8 10653.8 9340.6 10695.9 10707.0 10715.9 10717.5 2330.3 2381.4 2382.7 2384.6 2389.0 1961.5 2328.6 2331.3 2333.8 2350.4 2102.7 2335.6 2340.7 2343.6 2345.1 2052.6 2221.2 2295.6 2304.3 2314.2 2199.3 2302.4 2303.6 2314.3 2321.0 10776.9 10863.6 10870.6 10898.5 10924.5 5633.4 5860.4 5873.2 5879.4 5879.9 3324.5 3411.4 3611.3 3622.7 3632.0 1145.7 1152.6 1200.9 1225.2 1245.9 13280.9 13772.9 14029.4 14859.3 15031.4 4567.8 4665.2 4693.3 4693.5 4719.2 11258.8 11269.0 11269.2 11274.3 11283.0 4854.3 5668.7 5668.8 5673.2 5677.9 4416.7 4600.4 5170.0 5172.6 5173.8 3642.3 3714.6 3802.4 3836.8 3887.2 1036.4 1036.8 1043.2 1053.6 1056.2 909.3 944.0 949.0 958.3 984.3 6360.2 6468.8 6631.4 6688.5 6789.1 926.6 1005.5 1016.6 1018.0 1032.2 4175.7 4178.2 4185.2 4295.1 4297.5 12661.9 13047.0 13048.9 13051.3 13070.2 12753.9 12790.7 12927.7 12958.0 13179.9 9735.5 9831.2 9895.6 9908.2 9913.8 6251.2 6388.6 6411.2 6481.6 6503.2 11498.9 11893.8 11905.8 11916.0 11926.4 9883.9 10879.1 10963.8 11634.2 12091.5 7935.3 8197.4 8202.5 8204.5 8269.7 9701.1 9800.2 9822.8 9871.2 9889.9 8614.1 8723.3 8737.1 8925.9 8935.0 8417.0 8544.4 8568.9 8632.9 8649.2 7541.4 7579.5 7647.2 7653.7 7674.5 5716.5 5809.2 5819.8 5886.7 5915.4 5238.5 5572.9 5896.8 5899.9 6031.1 05:36:39 INFO - PID 1640 | 05:36:39 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5250.79 Median:5769.63 stddev:1515.89 (26.3%) stddev-sans-first:1474.91 05:36:39 INFO - PID 1640 | Values: 353.6 382.3 385.7 388.0 388.1 5475.7 5876.8 5887.7 5912.0 5941.4 4670.3 5562.5 5604.2 5816.7 5857.1 4496.5 4508.2 4522.4 4530.8 4542.3 4525.2 4533.8 4536.9 4558.2 4560.2 4166.9 4214.9 4484.5 4505.1 4545.1 3926.9 4350.9 4482.0 4488.2 4528.8 3974.8 3979.3 3983.9 3988.5 4014.6 5373.4 5675.4 5775.4 5819.2 5825.4 4923.6 5565.3 5580.9 5596.9 5646.6 5047.7 6260.6 6292.3 6343.9 6361.3 4891.5 4898.0 4924.5 4953.5 4972.5 6218.5 6410.2 6447.3 6538.3 6597.6 4816.3 4867.2 4876.9 4892.1 4900.4 4126.7 4642.7 4954.5 5074.7 5148.8 5363.3 5393.7 5534.8 5579.8 5598.3 5285.7 5531.8 5884.2 6227.4 6232.2 1755.3 1782.8 1786.2 1790.5 1809.0 1253.7 1281.8 1300.9 1304.1 1317.4 5749.9 5899.9 5937.0 5965.0 5965.7 6060.5 6084.8 6488.6 6531.1 6536.0 5282.7 5608.6 5905.7 5918.5 5919.3 3159.0 3362.4 3408.5 3421.2 3444.5 6386.7 6727.9 6739.6 6763.9 6805.0 6195.4 6653.3 6660.3 6668.7 6680.5 5857.9 6340.9 6763.3 6770.9 6788.2 6444.7 6520.4 6536.6 6573.2 6576.9 5693.4 6194.1 6476.7 6490.3 6525.1 6748.6 6806.9 6815.4 6821.3 6831.2 4987.9 5027.0 5092.4 5110.5 5120.5 5763.9 6325.4 6520.8 6559.3 6565.9 5364.7 6082.7 6293.0 6321.7 6387.4 6028.2 6283.8 6338.6 6361.4 6385.6 5238.8 6192.5 6217.5 6220.4 6226.6 6082.1 6343.6 6360.4 6388.7 6397.7 5809.6 6097.8 6138.9 6145.8 6151.3 05:36:39 INFO - PID 1640 | 05:36:39 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:19608.45 Median:25204.43 stddev:27244.42 (108.1%) stddev-sans-first:27284.08 05:36:39 INFO - PID 1640 | Values: 759.3 764.7 823.3 837.0 840.1 14747.7 14786.3 15444.9 15634.0 15636.2 14360.1 14508.0 14655.0 14702.5 14716.6 937.2 954.1 957.8 960.7 962.1 933.3 975.2 977.0 988.8 989.5 1032.6 1034.0 1035.1 1038.7 1039.8 1026.1 1026.3 1027.1 1029.9 1030.1 763.9 765.3 765.8 766.4 766.8 15595.1 15615.0 15927.7 16408.3 16446.3 14462.4 14663.0 14669.6 14729.3 14784.3 11553.7 11785.7 11950.9 11953.2 12184.9 3629.4 3860.7 3882.2 3882.7 3905.0 104263.6 107411.5 107514.5 111951.0 113929.0 11085.7 11292.3 11600.0 11750.2 11844.8 24852.5 25154.2 25192.1 25216.7 25303.0 7684.3 7789.1 7808.1 7809.8 7958.5 4095.1 4176.2 4183.9 4291.3 4395.8 3418.7 3438.9 3512.6 3518.5 3522.8 603.1 611.6 625.4 632.5 632.6 631.3 656.3 673.0 681.9 692.8 8488.4 9844.5 9941.4 10047.6 10509.8 631.2 648.7 665.7 716.3 718.6 4604.5 4847.4 4847.8 4855.5 4859.3 78903.6 83626.6 96794.0 100117.0 102734.5 69426.6 70636.6 77501.0 86766.6 90016.6 61607.7 64457.7 66446.3 69494.7 72224.0 10548.7 10845.0 10855.7 11013.6 11087.9 40197.4 44567.6 45672.3 47060.5 48584.0 60011.1 60012.4 60212.0 61366.4 61558.7 11812.9 13417.3 13651.6 14082.9 14145.6 13305.5 14781.7 14921.9 15043.3 15064.5 13960.7 13975.4 14117.7 14124.4 14847.4 12597.6 13432.7 13990.3 14084.6 14863.4 12845.7 13012.8 13316.5 13890.0 13939.9 10943.0 10953.0 10953.5 11034.9 11938.5 9912.6 11010.2 11118.3 11246.8 11316.7 05:36:39 INFO - PID 1640 | -------- Summary: end -------- 05:36:39 INFO - PID 1640 | 05:36:39 INFO - TEST-INFO | 1640: exit 0 05:36:39 INFO - TEST-OK | dromaeo_css | took 1121649ms 05:36:39 INFO - TEST-START | kraken 05:36:39 INFO - operating with platform_type : mac_ 05:36:39 INFO - Initialising browser for kraken test... 05:36:39 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/tmpcz4Jcq/profile http://localhost:49244/getInfo.html) 05:36:42 INFO - PID 1647 | __metrics Screen width/height:1600/1200 05:36:42 INFO - PID 1647 | colorDepth:24 05:36:42 INFO - PID 1647 | Browser inner width/height: 1024/689 05:36:42 INFO - PID 1647 | __metrics 05:36:42 INFO - PID 1647 | 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" 05:36:42 INFO - PID 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:36:43 INFO - TEST-INFO | 1647: exit 0 05:36:43 INFO - Browser initialized. 05:36:43 INFO - Running cycle 1/1 for kraken test... 05:36:43 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/T/tmpcz4Jcq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:36:55 INFO - PID 1649 | RSS: Main: 233447424 05:36:55 INFO - PID 1649 | 05:36:58 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 05:36:59 INFO - PID 1649 | RSS: Main: 285728768 05:36:59 INFO - PID 1649 | 05:37:01 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 05:37:02 INFO - PID 1649 | RSS: Main: 287539200 05:37:02 INFO - PID 1649 | 05:37:05 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 05:37:06 INFO - PID 1649 | RSS: Main: 377778176 05:37:06 INFO - PID 1649 | 05:37:08 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 05:37:09 INFO - PID 1649 | RSS: Main: 282513408 05:37:09 INFO - PID 1649 | 05:37:11 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:37:11 INFO - PID 1649 | RSS: Main: 284708864 05:37:11 INFO - PID 1649 | 05:37:14 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 05:37:15 INFO - PID 1649 | RSS: Main: 327020544 05:37:15 INFO - PID 1649 | 05:37:18 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 05:37:19 INFO - PID 1649 | RSS: Main: 333365248 05:37:19 INFO - PID 1649 | 05:37:22 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 05:37:22 INFO - PID 1649 | RSS: Main: 334135296 05:37:22 INFO - PID 1649 | 05:37:24 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:37:24 INFO - PID 1649 | RSS: Main: 303648768 05:37:24 INFO - PID 1649 | 05:37:26 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:37:27 INFO - PID 1649 | RSS: Main: 304259072 05:37:27 INFO - PID 1649 | 05:37:29 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:37:30 INFO - PID 1649 | RSS: Main: 307154944 05:37:30 INFO - PID 1649 | 05:37:32 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:37:33 INFO - PID 1649 | RSS: Main: 323960832 05:37:33 INFO - PID 1649 | 05:37:36 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:37:37 INFO - PID 1649 | RSS: Main: 317071360 05:37:37 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 05:37:39 INFO - PID 1649 | RSS: Main: 378662912 05:37:39 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | __start_tp_report 05:37:39 INFO - PID 1649 | _x_x_mozilla_page_load 05:37:39 INFO - PID 1649 | _x_x_mozilla_page_load_details 05:37:39 INFO - PID 1649 | |i|pagename|runs| 05:37:39 INFO - PID 1649 | |0;ai-astar;116;115;113;165;165;116;116;116;117;116 05:37:39 INFO - PID 1649 | |1;audio-beat-detection;143;111;112;149;110;110;107;110;133;110 05:37:39 INFO - PID 1649 | |2;audio-dft;185;184;181;185;185;184;183;184;197;242 05:37:39 INFO - PID 1649 | |3;audio-fft;77;61;59;91;83;58;61;60;77;59 05:37:39 INFO - PID 1649 | |4;audio-oscillator;87;72;88;68;54;65;87;98;96;111 05:37:39 INFO - PID 1649 | |5;imaging-gaussian-blur;88;87;88;87;88;87;87;92;88;87 05:37:39 INFO - PID 1649 | |6;imaging-darkroom;96;95;96;96;101;146;112;95;95;96 05:37:39 INFO - PID 1649 | |7;imaging-desaturate;124;134;84;86;84;84;84;86;84;84 05:37:39 INFO - PID 1649 | |8;json-parse-financial;61;61;61;61;62;61;62;61;63;62 05:37:39 INFO - PID 1649 | |9;json-stringify-tinderbox;56;63;60;67;62;58;58;60;60;60 05:37:39 INFO - PID 1649 | |10;stanford-crypto-aes;84;83;83;82;83;81;83;83;81;82 05:37:39 INFO - PID 1649 | |11;stanford-crypto-ccm;140;139;136;140;137;137;138;137;191;140 05:37:39 INFO - PID 1649 | |12;stanford-crypto-pbkdf2;187;182;185;183;178;170;182;182;189;222 05:37:39 INFO - PID 1649 | |13;stanford-crypto-sha256-iterative;68;67;66;61;59;69;89;91;90;90 05:37:39 INFO - PID 1649 | __end_tp_report 05:37:39 INFO - PID 1649 | __start_cc_report 05:37:39 INFO - PID 1649 | _x_x_mozilla_cycle_collect,2104 05:37:39 INFO - PID 1649 | __end_cc_report 05:37:39 INFO - PID 1649 | __startTimestamp1489754259586__endTimestamp 05:37:39 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | ------- Summary: start ------- 05:37:39 INFO - PID 1649 | Number of tests: 14 05:37:39 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | [#0] ai-astar Cycles:10 Average:125.50 Median:116.00 stddev:20.84 (18.0%) stddev-sans-first:21.82 05:37:39 INFO - PID 1649 | Values: 116.0 115.0 113.0 165.0 165.0 116.0 116.0 116.0 117.0 116.0 05:37:39 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | [#1] audio-beat-detection Cycles:10 Average:119.50 Median:111.50 stddev:15.81 (14.2%) stddev-sans-first:14.30 05:37:39 INFO - PID 1649 | Values: 143.0 111.0 112.0 149.0 110.0 110.0 107.0 110.0 133.0 110.0 05:37:39 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | [#2] audio-dft Cycles:10 Average:191.00 Median:185.00 stddev:18.43 (10.0%) stddev-sans-first:19.42 05:37:39 INFO - PID 1649 | Values: 185.0 184.0 181.0 185.0 185.0 184.0 183.0 184.0 197.0 242.0 05:37:39 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | [#3] audio-fft Cycles:10 Average:68.60 Median:69.00 stddev:12.19 (17.7%) stddev-sans-first:12.54 05:37:39 INFO - PID 1649 | Values: 77.0 61.0 59.0 91.0 83.0 58.0 61.0 60.0 77.0 59.0 05:37:39 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | [#4] audio-oscillator Cycles:10 Average:82.60 Median:87.00 stddev:17.46 (20.1%) stddev-sans-first:18.45 05:37:39 INFO - PID 1649 | Values: 87.0 72.0 88.0 68.0 54.0 65.0 87.0 98.0 96.0 111.0 05:37:39 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:88.00 stddev:1.52 (1.7%) stddev-sans-first:1.62 05:37:39 INFO - PID 1649 | Values: 88.0 87.0 88.0 87.0 88.0 87.0 87.0 92.0 88.0 87.0 05:37:39 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | [#6] imaging-darkroom Cycles:10 Average:102.80 Median:95.50 stddev:16.06 (16.8%) stddev-sans-first:16.85 05:37:39 INFO - PID 1649 | Values: 96.0 95.0 96.0 96.0 101.0 146.0 112.0 95.0 95.0 96.0 05:37:39 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | [#7] imaging-desaturate Cycles:10 Average:93.40 Median:84.00 stddev:18.93 (22.5%) stddev-sans-first:16.52 05:37:39 INFO - PID 1649 | Values: 124.0 134.0 84.0 86.0 84.0 84.0 84.0 86.0 84.0 84.0 05:37:39 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | [#8] json-parse-financial Cycles:10 Average:61.50 Median:61.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 05:37:39 INFO - PID 1649 | Values: 61.0 61.0 61.0 61.0 62.0 61.0 62.0 61.0 63.0 62.0 05:37:39 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.00 stddev:3.06 (5.1%) stddev-sans-first:2.80 05:37:39 INFO - PID 1649 | Values: 56.0 63.0 60.0 67.0 62.0 58.0 58.0 60.0 60.0 60.0 05:37:39 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.00 stddev:0.97 (1.2%) stddev-sans-first:0.87 05:37:39 INFO - PID 1649 | Values: 84.0 83.0 83.0 82.0 83.0 81.0 83.0 83.0 81.0 82.0 05:37:39 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:143.50 Median:139.50 stddev:16.75 (12.0%) stddev-sans-first:17.72 05:37:39 INFO - PID 1649 | Values: 140.0 139.0 136.0 140.0 137.0 137.0 138.0 137.0 191.0 140.0 05:37:39 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.00 Median:184.00 stddev:13.68 (7.4%) stddev-sans-first:14.50 05:37:39 INFO - PID 1649 | Values: 187.0 182.0 185.0 183.0 178.0 170.0 182.0 182.0 189.0 222.0 05:37:39 INFO - PID 1649 | 05:37:39 INFO - PID 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.00 Median:79.00 stddev:13.27 (16.8%) stddev-sans-first:13.83 05:37:39 INFO - PID 1649 | Values: 68.0 67.0 66.0 61.0 59.0 69.0 89.0 91.0 90.0 90.0 05:37:39 INFO - PID 1649 | -------- Summary: end -------- 05:37:39 INFO - PID 1649 | 05:37:40 INFO - TEST-INFO | 1649: exit 0 05:37:40 INFO - TEST-OK | kraken | took 60872ms 05:37:40 INFO - SUITE-END | took 1182s 05:37:40 INFO - Completed test suite (00:19:42) 05:37:40 INFO - javascript benchmark 05:37:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7783.060400991295, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3311.7019468247854, 3383.6616338366166, 3404.6084700259476, 3406.0973841931886, 3434.1414646338417, 5697.173909783079, 5777.884442311151, 5901.380355062715, 5912.70436478176, 5934.198883150768, 5823.5632327575495, 5824.621399609023, 5878.7648494060295, 5909.054551271797, 5959.195508606337, 5202.583793296527, 5263.552598029162, 5279.70961597112, 5288.365396152467, 5296.046711591912, 5347.064263753847, 5368.409474957752, 5372.677639341632, 5420.7018613976215, 5456.454354564532, 5220.817271395483, 5280.313559237313, 5283.313169288006, 5288.0745869472685, 5332.253484512152, 5259.921101183485, 5288.418273989858, 5290.39160496544, 5291.497307755757, 5297.496737809902, 5211.062008838408, 5245.213218017289, 5248.0, 5255.553277971377, 5273.419923808378, 5754.741036653319, 5759.078547432391, 5761.164631128463, 5779.2775903012125, 5781.450762177628, 6253.280872699653, 6272.56092073555, 6272.968635156841, 6275.497960163176, 6286.528510361742, 4773.95226047742, 6272.686365681698, 6316.273628532732, 6317.810465686037, 6322.52581056418, 6044.093385992049, 6044.123602077718, 6045.909311360333, 6051.515878729647, 6051.636901785863, 6228.190335256388, 6259.71831267594, 6436.324185960407, 6438.6136831790245, 6451.967740161269, 6175.59858609189, 6204.224471941007, 6245.906311405332, 6287.465565426898, 6294.370562943669, 6324.715387807468, 6326.114343991845, 6328.303886572473, 6346.904796428057, 6370.968145159244, 5366.302380690485, 6298.622082675054, 6303.212098487689, 6312.400321969406, 6331.271903731038, 6214.25428948529, 6279.152314437492, 6287.685615719287, 6291.087792270096, 6293.276273228637, 4949.337684107841, 5015.398152221756, 5018.5985121190215, 5021.221710634857, 5039.521245481674, 4499.370088187657, 4509.481409637933, 4540.77296135192, 4561.110583436198, 4568.840090596813, 6059.0, 6106.582013105394, 6115.827502811564, 6117.877642447126, 6124.411449640054, 6299.338569450233, 6315.242170939425, 6318.178636777189, 6332.050192471166, 6345.49236061114, 5851.444112809277, 5930.762769489258, 5932.2584676915385, 6065.120557519223, 6090.725917333731, 5573.860653483695, 5584.5811564132855, 5607.299087614048, 5611.635243709227, 5626.127950167731, 6508.381703738138, 6525.934740652628, 6542.378474044863, 6542.443892269211, 6556.901646475402, 6231.40801623845, 6297.779577714758, 6304.3380445054445, 6307.653079080695, 6318.178636777189, 6332.366763323631, 6430.774922877864, 6498.122753428416, 6512.511561632991, 6532.83667908287, 6245.96877015612, 6248.437640612185, 6273.968630157003, 6275.435210831047, 6303.747850085945, 4894.779734911866, 6175.660338681236, 6180.721867516063, 6245.125682404376, 6262.405071518198, 5308.734563271898, 6075.969620151871, 6115.84710382244, 6226.875462490815, 6260.092286618506, 6245.219347581552, 6255.906161407674, 6262.060690896402, 6269.717862696099, 6271.43557079865, 6102.603330783485, 6144.201253836973, 6158.445739883432, 6161.876762464636, 6171.043488259327, 6131.938680613137, 6167.691615419121, 6183.5671502994355, 6187.628742275478, 6235.033569796688, 6115.296740874678, 6123.545744966725, 6146.385361463817, 6152.692365381802, 6167.784127555514], "value": 5800.718178606677, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [666.7932940788354, 705.6013352455863, 785.5483097219098, 852.2244757270885, 864.299917067176, 27012.05457808977, 27410.31474213143, 35948.82025589875, 36456.817715911384, 36546.8172659137, 37517.43723844138, 39324.41013384802, 39347.40978885319, 39362.40956385649, 39414.21171576567, 8954.32842536809, 9151.725448236564, 9169.312301577374, 9171.862422063641, 9174.495402752846, 7069.081019467462, 7226.5302755321, 8921.0, 10513.0, 10741.731456713605, 10723.946380268088, 10800.081993030602, 10845.969632884864, 10935.070519005854, 10975.2866063706, 10808.243422960395, 10920.563177472892, 10923.836142457869, 10926.0, 10959.287646303004, 7019.473539484508, 7194.064771579661, 7194.964025179893, 7203.17163526196, 10509.527071281735, 26005.0, 34804.477932831025, 35245.471317929994, 35367.64632353669, 35377.11557211065, 3766.962330376716, 3778.622137786227, 3801.8859434216743, 3830.367989281765, 3936.2717897189114, 26003.73996260051, 26806.0, 27180.184594462196, 27713.307167320778, 27764.30589235285, 3147.134538002045, 3147.213196700825, 3159.7472202223767, 3280.4751239801517, 3286.6877646623534, 292104.0, 295569.0, 295883.99999999785, 295983.0, 296088.0, 46417.0, 46525.53474465212, 46566.767166163954, 46617.0, 46649.53350466452, 49255.50744492509, 49266.50733492691, 49351.753241234284, 49487.752561237685, 49489.0, 13952.302384880715, 14159.85840141606, 14173.433062677585, 14174.645633859236, 14186.148831070168, 11671.241369310968, 11703.590374336858, 11709.414529273503, 11709.531618735326, 11727.589534366427, 9967.601295948081, 10108.595656173818, 10127.0, 11435.256708313933, 11640.126990475748, 5124.743762811844, 5464.316960379952, 5535.391106978229, 5550.7779688812, 5562.499375056264, 1082.9025387714985, 1094.7427354571669, 1102.2284400919389, 1113.287496002559, 1158.8145896656495, 26041.224022516628, 26214.95140194409, 26875.73124268732, 26928.59607105895, 26947.326316842235, 1142.4516232208587, 1158.79141754485, 1194.9820752688718, 1200.9459574319176, 1208.6615747590513, 13664.590062297948, 13782.517611883535, 13874.236916969461, 13975.510857119953, 14105.647358815906, 394727.02636487206, 395108.0, 396270.0, 396509.0, 396510.0, 46204.8448788783, 51122.74438627783, 52607.73696131571, 53021.469785301655, 53109.0, 1410.0904916329116, 1434.5767998440695, 1436.4110714606961, 1438.96402589934, 1471.02912078028, 25046.874765626057, 25927.481450371266, 26124.60813087767, 26188.607170892832, 26350.341241469116, 35788.0, 42584.57415425806, 42655.7203283902, 42681.573184269, 42867.57132428636, 366099.0, 367921.0, 368837.0, 369568.0, 370604.0, 28923.855380722962, 31140.53289200709, 31880.88416905397, 34057.97826065222, 35691.64308356883, 45087.774561128295, 45142.0, 45538.0, 45756.31365529453, 45989.540104599866, 36365.45451818278, 36976.63023369732, 37336.64903949338, 38037.0, 38079.80960095182, 38118.04704882289, 38157.09214539317, 38211.61788382192, 39237.0, 39261.60738392694, 33759.324813504085, 38713.20876769654, 38735.03162420962, 39266.80366598149, 39530.802345988086, 30975.380492390475, 32930.0, 34058.22640291896, 34290.0, 35031.0, 22949.311520654406, 23424.18015369454, 25381.365465863502, 25830.87084564565, 25843.0], "value": 18127.13641838828, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.0467932508392, 396.728241154809, 405.2624223912479, 411.3336395040035, 426.17960426179616, 12653.493860245591, 13051.347432628367, 16904.915475422622, 21008.684869726967, 21056.368308950718, 21678.89160554195, 21954.34136975893, 22647.886760566173, 22745.545089098207, 22825.657615135744, 6269.968650156743, 6367.4269315761685, 6496.220453545581, 6614.305497922721, 6693.933060669404, 4105.527864295615, 6478.416942475176, 6605.801825945229, 6608.702608382634, 6614.305497922697, 4348.760818155, 4401.515833258339, 7380.520266182708, 7384.815379615499, 7389.113306403212, 7285.235050319693, 7340.265973402644, 7354.338109570164, 7393.778186654409, 7424.925750742477, 6976.372126508588, 7195.532290401159, 7197.496175267734, 7203.567785932861, 7204.56772593646, 26245.21264362072, 27372.178834634993, 27432.725672743218, 27519.44961100787, 27683.307917302027, 2909.229054300612, 2920.5765164051095, 2933.779966502521, 2943.2494713848046, 3059.479888418974, 16484.34062637493, 16558.917205414018, 16671.66656666872, 16690.33238670452, 17646.11769411537, 1976.9110390032483, 2004.1081718635214, 2004.7093171490053, 2010.4270282969283, 2011.6177926193977, 135399.0, 150942.0, 151946.0, 152257.0, 155251.0, 17340.65318693619, 20852.0, 20910.895445522674, 21202.653456041473, 21450.463738406666, 28793.99221027254, 35127.4730879037, 35128.82435587806, 35217.83039526455, 35338.29323413517, 9985.850212246822, 10170.033846784543, 10378.636747713794, 10378.792424151474, 10585.788284234271, 8377.413581049268, 8383.161683831568, 8558.058613552505, 8579.399442038997, 8581.699640512677, 9993.550290236957, 10311.123554497952, 10420.196628230642, 10432.0, 10437.947810260901, 3389.356022355794, 3888.425187798946, 3924.293627147141, 3926.6466018058513, 3945.506811648544, 934.4907025671025, 986.358866736627, 999.1058003087196, 1002.1431675916986, 1035.7773078787945, 22268.0, 22706.43233919165, 22727.545449090925, 22752.317430477113, 22796.4300892479, 984.4241118945354, 992.4888682328607, 1011.1051719228388, 1014.3761586624321, 1017.8524114003428, 10857.565697372016, 11522.193446458836, 11629.825552616718, 11635.709107272385, 11654.53381864733, 687953.0, 693532.0, 701946.0, 707958.0, 720001.0, 107015.9298407006, 110991.1070375777, 115598.8440115588, 145743.0, 150350.0, 1242.912996090265, 1268.9936550317188, 1300.6098170548798, 1302.5180683147107, 1308.1366298243113, 20078.0, 21384.823834689243, 21399.0, 21423.143074276853, 21498.355049348545, 50331.0, 50623.931963597875, 60867.39132608794, 61813.690931545054, 62183.0, 86387.56806215929, 86557.0, 88144.55927720577, 88903.55548222477, 89252.0, 11682.299062056303, 11716.472758726048, 11822.881771182521, 12116.575919842764, 12154.453049612965, 13139.671508212372, 13271.203727776365, 13576.321183940961, 13728.244946528037, 14180.645483862574, 10956.56173753041, 11007.0, 11258.549658013588, 11503.367314797766, 11557.826632600685, 10947.671569852917, 11127.443627818415, 11341.376224307412, 11383.772324553629, 11764.82352764692, 11471.369074700971, 11570.421478925851, 11835.763284734428, 11851.881481185077, 11943.16397852142, 11601.82597261025, 11661.883381166079, 11900.345480998629, 11931.940340298243, 12062.75874482523, 11017.283876548, 11088.112950964065, 11358.488868000795, 11401.828972565583, 11552.960233579019], "value": 11570.888908623601, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1511.8714909232715, 1555.906645601264, 1572.677601091776, 1583.517027306671, 1585.0, 10181.185505159589, 10623.628173013945, 10624.0, 10639.840402393971, 10653.84019239712, 9340.626374944994, 10695.893041069587, 10707.0, 10715.892841071587, 10717.464126793668, 2330.33585428153, 2381.404648837791, 2382.7259865115475, 2384.558856611524, 2388.9880550597222, 1961.5096225943514, 2328.592496313147, 2331.265651319829, 2333.8249631277636, 2350.388898886293, 2102.748864425672, 2335.5912715274844, 2340.719113706358, 2343.5898717724417, 2345.061975209913, 2052.6100040992164, 2221.244776775904, 2295.598270302699, 2304.3087073877937, 2314.2247347138727, 2199.3401979406112, 2302.378357843373, 2303.57383883982, 2314.340412982292, 2321.0, 10776.892231077667, 10863.565457381774, 10870.61952831647, 10898.455077246184, 10924.453777311182, 5633.40849210831, 5860.413958604106, 5873.236479257712, 5879.353271140214, 5879.941200588025, 3324.501324801275, 3411.4371128763723, 3611.295797319524, 3622.6920711739535, 3631.981840090809, 1145.7192987717979, 1152.6196355202762, 1200.9159358844884, 1225.1852518075468, 1245.850497940253, 13280.937524998073, 13772.931135344359, 14029.43882244709, 14859.331330090172, 15031.398744050224, 4567.771611419415, 4665.183592871234, 4693.272542755878, 4693.48371679115, 4719.197736384823, 11258.774824503464, 11269.0, 11269.21115521922, 11274.323540587591, 11283.0, 4854.344663470386, 5668.716564171775, 5668.773249070074, 5673.234113394639, 5677.858053548694, 4416.734995900192, 4600.378948841931, 5170.0, 5172.637915345889, 5173.818916337986, 3642.2715456908722, 3714.647108524686, 3802.4106263529197, 3836.8081595920094, 3887.2031233597045, 1036.3937096798456, 1036.7978244242452, 1043.2175868098925, 1053.6048981631889, 1056.1972900595604, 909.3225546967551, 943.9811203775886, 948.9525523723786, 958.2956526952685, 984.3454103021554, 6360.236771587347, 6468.773592924225, 6631.3700198481065, 6688.4649228061635, 6789.083473730983, 926.6200857648466, 1005.5022763732, 1016.5577973581516, 1017.9847302290472, 1032.2361452525076, 4175.665946724254, 4178.1643671265865, 4185.24665560196, 4295.140971805651, 4297.505786834491, 12661.936690316674, 13047.0, 13048.934755326352, 13051.347432628521, 13070.215787052808, 12753.872461275454, 12790.744185116431, 12927.741445171045, 12958.028147888946, 13179.868201318055, 9735.464549449849, 9831.213502919893, 9895.554700038516, 9908.20734341251, 9913.80172396548, 6251.218597675291, 6388.616682999035, 6411.230652321751, 6481.578697384655, 6503.154589903472, 11498.94250528742, 11893.821592675944, 11905.821412678644, 11915.987141093105, 11926.403679816149, 9883.851742224017, 10879.129669626569, 10963.780724385628, 11634.185607007428, 12091.516339346328, 7935.32549733256, 8197.42618016757, 8202.507849529047, 8204.54874981858, 8269.710560130367, 9701.12689857888, 9800.215982721507, 9822.75443113928, 9871.160951319218, 9889.851652225367, 8614.138586141336, 8723.302135829246, 8737.100834900608, 8925.866112008196, 8935.0, 8416.957915210385, 8544.359173061921, 8568.871466928125, 8632.870506942276, 8649.221570058726, 7541.396688265036, 7579.54522728638, 7647.197044310267, 7653.732119375795, 7674.501157424749, 5716.5426765859465, 5809.244798176379, 5819.76720931175, 5886.676232807236, 5915.37888521699, 5238.476152384731, 5572.9411411831625, 5896.793612223723, 5899.882002360015, 6031.095335699505], "value": 4983.978919900392, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.6039635608119, 382.2755877611925, 385.652912378859, 387.9747816391936, 388.1112252940766, 5475.67145971242, 5876.8236952891375, 5887.676177810224, 5912.0, 5941.435563621449, 4670.346151538787, 5562.499375056244, 5604.187392828037, 5816.738246778895, 5857.092150716635, 4496.505384407713, 4508.188526065307, 4522.3668686383935, 4530.75080870552, 4542.273236282195, 4525.162844873693, 4533.795979180928, 4536.867672194064, 4558.236642708378, 4560.156371071363, 4166.91666166678, 4214.852480163195, 4484.542523679801, 4505.144022635705, 4545.068261006502, 3926.862559810407, 4350.934735978963, 4482.036362182114, 4488.182286174105, 4528.818847246106, 3974.8211330489908, 3979.3235150024566, 3983.860564880244, 3988.541317748438, 4014.558398576154, 5373.408925018245, 5675.3757086720425, 5775.358337911435, 5819.156222347779, 5825.38833422493, 4923.58149557288, 5565.276514053187, 5580.888382232373, 5596.908602822451, 5646.57650676201, 5047.722375269359, 6260.561760676755, 6292.276388215368, 6343.936560634381, 6361.300256971729, 4891.461939186686, 4897.975510122462, 4924.482929292444, 4953.455119936804, 4972.5276098770555, 6218.533609979269, 6410.1987251593555, 6447.291467761047, 6538.346165383424, 6597.571157874723, 4816.277558366273, 4867.221244600847, 4876.878078048077, 4892.143874821892, 4900.411950565955, 4126.711130220856, 4642.651801114863, 4954.47977962316, 5074.746262686851, 5148.768305426265, 5363.302770639792, 5393.6763794172475, 5534.806281780118, 5579.776808927679, 5598.272224610774, 5285.656432331886, 5531.751071201806, 5884.234729581109, 6227.353374815685, 6232.189815323979, 1755.3154269834774, 1782.803891571926, 1786.1515780004395, 1790.5255107396551, 1809.0412081596776, 1253.655244807667, 1281.7628738683372, 1300.947962081525, 1304.1327517201146, 1317.3808310094241, 5749.913751293734, 5899.852503687356, 5936.970315148396, 5965.015772397593, 5965.671888046112, 6060.515158787286, 6084.817455476343, 6488.578242414228, 6531.085648009187, 6536.0, 5282.709450980156, 5608.551315894719, 5905.696778256483, 5918.467337939606, 5919.289685237713, 3159.0, 3362.445196542592, 3408.4887266910164, 3421.23022319976, 3444.4661077533024, 6386.712597933104, 6727.899081513683, 6739.561928474732, 6763.864722705518, 6805.0, 6195.411435913581, 6653.2681405045405, 6660.267370589231, 6668.699908504129, 6680.498962577729, 5857.941420585739, 6340.9365906340345, 6763.323667633295, 6770.898436523456, 6788.185417749902, 6444.677766111582, 6520.380563846427, 6536.607803531803, 6573.244076931214, 6576.868462630625, 5693.373728889736, 6194.132821404917, 6476.740930362733, 6490.253620833476, 6525.0538671893255, 6748.628825414649, 6806.860820080808, 6815.386615204656, 6821.317868213139, 6831.248562658203, 4987.850364489072, 5026.969471258456, 5092.439831618593, 5110.48895110486, 5120.539151476385, 5763.907625585881, 6325.430711236011, 6520.771772987922, 6559.311272316338, 6565.901511477236, 5364.731763411892, 6082.72627731762, 6292.993121100602, 6321.747130114928, 6387.361263873576, 6028.246469191351, 6283.780067697791, 6338.556301058881, 6361.395667411589, 6385.584936979268, 5238.790448382022, 6192.513796688672, 6217.502599791916, 6220.377962203744, 6226.59527130735, 6082.087686847009, 6343.5876668016435, 6360.427561519486, 6388.722255548816, 6397.712102955285, 5809.564282678732, 6097.75608975636, 6138.8643101026455, 6145.84635384119, 6151.32335443092], "value": 4793.879057111947, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.2635143910405, 764.6520833020976, 823.3084209264217, 836.9958150209249, 840.1304649687572, 14747.705045899076, 14786.260686965648, 15444.918855680093, 15634.0, 15636.218189090529, 14360.138391696506, 14508.0, 14655.0, 14702.485413010547, 14716.558503244893, 937.2314701944408, 954.0793134625086, 957.8275910336137, 960.7165886063628, 962.1340793286064, 933.3233405780788, 975.2198241406846, 977.0327375897881, 988.8467287570438, 989.5200827598609, 1032.6385764982233, 1034.0, 1035.0684384054337, 1038.7455073507, 1039.776448063666, 1026.0509029148009, 1026.3431403901473, 1027.0705011964185, 1029.903153141909, 1030.0626429948786, 763.8815983522563, 765.3341592814231, 765.7587859824193, 766.4481573267238, 766.758471081613, 15595.1422671753, 15615.0, 15927.681446371007, 16408.343666253455, 16446.259918303705, 14462.421503139862, 14663.0, 14669.633259168499, 14729.337179826933, 14784.260786960716, 11553.653390398302, 11785.705357366049, 11950.940245298805, 11953.163278570504, 12184.878151218463, 3629.4374371972385, 3860.7490513116836, 3882.165334453078, 3882.7282090253693, 3905.0042239229097, 104263.63935636476, 107411.46294268478, 107514.46242768737, 111951.0, 113929.0, 11085.722856928642, 11292.266002709797, 11600.0, 11750.236234644892, 11844.822327665092, 24852.5029499409, 25154.24537263885, 25192.118275860255, 25216.747832521807, 25303.0, 7684.269994350528, 7789.111929995382, 7808.141104478503, 7809.804754881174, 7958.482698624571, 4095.140020595649, 4176.202793071457, 4183.937240941388, 4291.313389857608, 4395.758233297135, 3418.743594230443, 3438.948415773765, 3512.631173726773, 3518.5074089627474, 3522.788632682047, 603.0682595390045, 611.5657882903099, 625.3871206217932, 632.5161251642497, 632.5666918161074, 631.3150231998334, 656.297761395312, 672.9831754206184, 681.9215790184193, 692.757534862794, 8488.363372746946, 9844.458554779556, 9941.353812002197, 10047.64833230848, 10509.84235236472, 631.1952260867431, 648.7437462202498, 665.6704931059156, 716.2515171645642, 718.5975853522044, 4604.516525764813, 4847.442544107403, 4847.830325938575, 4855.465898751135, 4859.271109333628, 78903.60548197222, 83626.58186709028, 96794.0, 100117.0, 102734.48632756788, 69426.61146777036, 70636.64681676662, 77501.0, 86766.56616716876, 90016.6497502538, 61607.6919615399, 64457.67771161114, 66446.33553664401, 69494.65252673705, 72224.0, 10548.736281593021, 10845.0, 10855.728606784893, 11013.614523491638, 11087.944560277147, 40197.39703904502, 44567.55432445634, 45672.31491527563, 47060.52939470699, 48584.01195778792, 60011.09983350341, 60012.39987600068, 60212.0, 61366.38633613606, 61558.69220653868, 11812.881871181178, 13417.322834645669, 13651.590452286448, 14082.859171408154, 14145.575632731034, 13305.46778128864, 14781.704365912836, 14921.925390372979, 15043.323050462974, 15064.472743454366, 13960.720785584435, 13975.371108299947, 14117.717645646824, 14124.364403601658, 14847.40610375573, 12597.559085432333, 13432.731345373233, 13990.300484975507, 14084.647883802987, 14863.405463781328, 12845.743085138432, 13012.8048079277, 13316.533921312708, 13890.0, 13939.860601393857, 10943.0, 10953.0, 10953.452327383759, 11034.944825275823, 11938.462769175581, 9912.553935073083, 11010.174236932102, 11118.277311974696, 11246.775064498828, 11316.660500185008], "value": 7645.8426637269495, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1480.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 113.0, 165.0, 165.0, 116.0, 116.0, 116.0, 117.0, 116.0], "value": 125.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 111.0, 112.0, 149.0, 110.0, 110.0, 107.0, 110.0, 133.0, 110.0], "value": 119.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 181.0, 185.0, 185.0, 184.0, 183.0, 184.0, 197.0, 242.0], "value": 191.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 61.0, 59.0, 91.0, 83.0, 58.0, 61.0, 60.0, 77.0, 59.0], "value": 68.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 72.0, 88.0, 68.0, 54.0, 65.0, 87.0, 98.0, 96.0, 111.0], "value": 82.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 87.0, 88.0, 87.0, 87.0, 92.0, 88.0, 87.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 101.0, 146.0, 112.0, 95.0, 95.0, 96.0], "value": 102.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 134.0, 84.0, 86.0, 84.0, 84.0, 84.0, 86.0, 84.0, 84.0], "value": 93.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 62.0, 61.0, 62.0, 61.0, 63.0, 62.0], "value": 61.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 60.0, 67.0, 62.0, 58.0, 58.0, 60.0, 60.0, 60.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 82.0, 83.0, 81.0, 83.0, 83.0, 81.0, 82.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 136.0, 140.0, 137.0, 137.0, 138.0, 137.0, 191.0, 140.0], "value": 143.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 182.0, 185.0, 183.0, 178.0, 170.0, 182.0, 182.0, 189.0, 222.0], "value": 186.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 61.0, 59.0, 69.0, 89.0, 91.0, 90.0, 90.0], "value": 75.0, "unit": "score"}]}]} 05:37:40 INFO - Return code: 0 05:37:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:37:40 ERROR - # TBPL SUCCESS # 05:37:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:37:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:37:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:37:40 INFO - 2017-03-17 05:37:40.874 screenresolution[1653:9906] starting screenresolution argv=screenresolution get 05:37:40 INFO - 2017-03-17 05:37:40.877 screenresolution[1653:9906] Display 0: 1600x1200x32@60 05:37:40 INFO - 2017-03-17 05:37:40.883 screenresolution[1654:9910] starting screenresolution argv=screenresolution list 05:37:40 INFO - Available Modes on Display 0 05:37:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:37:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:37:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:37:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:37:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:37:41 INFO - Intel Iris: 05:37:41 INFO - Chipset Model: Intel Iris 05:37:41 INFO - Type: GPU 05:37:41 INFO - Bus: Built-In 05:37:41 INFO - VRAM (Dynamic, Max): 1536 MB 05:37:41 INFO - Vendor: Intel (0x8086) 05:37:41 INFO - Device ID: 0x0a2e 05:37:41 INFO - Revision ID: 0x0009 05:37:41 INFO - Displays: 05:37:41 INFO - Display: 05:37:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:37:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:37:41 INFO - Display Serial Number: 1600x1200 60 05:37:41 INFO - Main Display: Yes 05:37:41 INFO - Mirror: Off 05:37:41 INFO - Online: Yes 05:37:41 INFO - Rotation: Supported 05:37:41 INFO - Return code: 0 05:37:41 INFO - Running post-action listener: _resource_record_post_action 05:37:41 INFO - [mozharness: 2017-03-17 12:37:41.122039Z] Finished run-tests step (success) 05:37:41 INFO - Running post-run listener: _resource_record_post_run 05:37:41 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 32603136; Write bytes: 513539584; Read time: 201; Write time: 1605 05:37:41 INFO - TinderboxPrint: CPU usage
53.5% 05:37:41 INFO - TinderboxPrint: I/O read bytes / time
32,603,136 / 201 05:37:41 INFO - TinderboxPrint: I/O write bytes / time
513,539,584 / 1,605 05:37:41 INFO - TinderboxPrint: CPU idle
2,239.1 (46.5%) 05:37:41 INFO - TinderboxPrint: CPU system
957.8 (19.9%) 05:37:41 INFO - TinderboxPrint: CPU user
1,620.9 (33.6%) 05:37:41 INFO - TinderboxPrint: Swap in / out
238,342,144 / 0 05:37:41 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 211356160; Write bytes: 213198336; Read time: 16996; Write time: 491 05:37:41 INFO - run-tests - Wall time: 1184s; CPU: 54.0%; Read bytes: 28924928; Write bytes: 298936320; Read time: 176; Write time: 1110 05:37:41 INFO - Running post-run listener: _upload_blobber_files 05:37:41 INFO - Blob upload gear active. 05:37:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:37:41 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 05:37:41 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'] 05:37:41 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 05:37:41 INFO - (blobuploader) - INFO - Open directory for files ... 05:37:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:37:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:37:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:42 INFO - (blobuploader) - INFO - Done attempting. 05:37:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:37:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:37:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:43 INFO - (blobuploader) - INFO - Done attempting. 05:37:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:37:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:37:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:44 INFO - (blobuploader) - INFO - Done attempting. 05:37:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:37:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:37:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:46 INFO - (blobuploader) - INFO - Done attempting. 05:37:46 INFO - (blobuploader) - INFO - Iteration through files over. 05:37:46 INFO - Return code: 0 05:37:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:37:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:37:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2f5126949be7441167a0d88f8e5d45d11e709e07eb13804ef5604267ad8de182511a7ce69d3f8f65730cf1a8d027f6c3dd2015e70896286dbf94cb019c21231", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6740426c310e132925d8504f44f371e63d9c1f54ba577518f3567f274f0f4c8b314417a46091cf1b61230d84416f1417f46e874077bc36f312acbf18408ec2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af66072df506f390d5dea10e37640d34b6335b9cf7931b858d4da707f9d132439579f5487bb9f84f1b7ca85149be24ce0ff3d42e92b6d8e65a7ff49751a5ef88", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f3a2f7dc161d549f91820f4af1739db31b9c91e17bab23fbe3f7abcc9b6e953138c3500a4b5d3562d3da538573403ea16531e29419077e57f10ff26d66d17f6f"} 05:37:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:37:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:37:46 INFO - Contents: 05:37:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2f5126949be7441167a0d88f8e5d45d11e709e07eb13804ef5604267ad8de182511a7ce69d3f8f65730cf1a8d027f6c3dd2015e70896286dbf94cb019c21231", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6740426c310e132925d8504f44f371e63d9c1f54ba577518f3567f274f0f4c8b314417a46091cf1b61230d84416f1417f46e874077bc36f312acbf18408ec2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af66072df506f390d5dea10e37640d34b6335b9cf7931b858d4da707f9d132439579f5487bb9f84f1b7ca85149be24ce0ff3d42e92b6d8e65a7ff49751a5ef88", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f3a2f7dc161d549f91820f4af1739db31b9c91e17bab23fbe3f7abcc9b6e953138c3500a4b5d3562d3da538573403ea16531e29419077e57f10ff26d66d17f6f"} 05:37:46 INFO - Running post-run listener: copy_logs_to_upload_dir 05:37:46 INFO - Copying logs to upload dir... 05:37:46 INFO - mkdir: /builds/slave/test/build/upload/logs 05:37:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1303.519433 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 43 secs) (at 2017-03-17 05:37:46.851252) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-17 05:37:46.854529) ========= 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.aDJ06KSdmg/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.jt4oiGOnV6/Listeners TMPDIR=/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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/c2f5126949be7441167a0d88f8e5d45d11e709e07eb13804ef5604267ad8de182511a7ce69d3f8f65730cf1a8d027f6c3dd2015e70896286dbf94cb019c21231", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6740426c310e132925d8504f44f371e63d9c1f54ba577518f3567f274f0f4c8b314417a46091cf1b61230d84416f1417f46e874077bc36f312acbf18408ec2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af66072df506f390d5dea10e37640d34b6335b9cf7931b858d4da707f9d132439579f5487bb9f84f1b7ca85149be24ce0ff3d42e92b6d8e65a7ff49751a5ef88", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f3a2f7dc161d549f91820f4af1739db31b9c91e17bab23fbe3f7abcc9b6e953138c3500a4b5d3562d3da538573403ea16531e29419077e57f10ff26d66d17f6f"} build_url:https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011227 build_url: 'https://queue.taskcluster.net/v1/task/AP0fiyKyTzuajdbAROnBOA/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/c2f5126949be7441167a0d88f8e5d45d11e709e07eb13804ef5604267ad8de182511a7ce69d3f8f65730cf1a8d027f6c3dd2015e70896286dbf94cb019c21231", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6740426c310e132925d8504f44f371e63d9c1f54ba577518f3567f274f0f4c8b314417a46091cf1b61230d84416f1417f46e874077bc36f312acbf18408ec2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af66072df506f390d5dea10e37640d34b6335b9cf7931b858d4da707f9d132439579f5487bb9f84f1b7ca85149be24ce0ff3d42e92b6d8e65a7ff49751a5ef88", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f3a2f7dc161d549f91820f4af1739db31b9c91e17bab23fbe3f7abcc9b6e953138c3500a4b5d3562d3da538573403ea16531e29419077e57f10ff26d66d17f6f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-17 05:37:46.889493) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 05:37:46.889849) ========= 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.aDJ06KSdmg/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.jt4oiGOnV6/Listeners TMPDIR=/var/folders/j2/21n1zhy52bldgl85sg0cdxlc00000w/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.005079 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 05:37:46.939072) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-17 05:37:46.939425) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-17 05:37:46.939762) ========= ========= Total master_lag: 0.19 =========