builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0324 starttime: 1472697123.46 results: success (0) buildid: 20160831171120 builduid: cff71ffda8a240359be28a872f8d8757 revision: 9e00b305da24074ae5aba1227beb5e096b026100 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 19:32:03.463841) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 19:32:03.464267) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 19:32:03.464540) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 19:32:03.503012) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 19:32:03.503331) ========= 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.yKMUC5qelw/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.VJB91QZNkE/Listeners TMPDIR=/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 --2016-08-31 19:32:03-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.5M=0.001s 2016-08-31 19:32:03 (18.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124211 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 19:32:03.650625) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 19:32:03.650946) ========= 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.yKMUC5qelw/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.VJB91QZNkE/Listeners TMPDIR=/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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.024576 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 19:32:03.699517) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 19:32:03.702942) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9e00b305da24074ae5aba1227beb5e096b026100 --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 9e00b305da24074ae5aba1227beb5e096b026100 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yKMUC5qelw/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.VJB91QZNkE/Listeners TMPDIR=/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 2016-08-31 19:32:03,735 truncating revision to first 12 chars 2016-08-31 19:32:03,735 Setting DEBUG logging. 2016-08-31 19:32:03,735 attempt 1/10 2016-08-31 19:32:03,735 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9e00b305da24?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 19:32:04,748 unpacking tar archive at: mozilla-central-9e00b305da24/testing/mozharness/ program finished with exit code 0 elapsedTime=1.279931 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 19:32:05.008942) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 19:32:05.009286) ========= script_repo_revision: 9e00b305da24074ae5aba1227beb5e096b026100 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 19:32:05.009718) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 19:32:05.009985) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 19:32:05.029680) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-31 19:32:05.030079) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--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.yKMUC5qelw/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.VJB91QZNkE/Listeners TMPDIR=/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 19:32:05 INFO - MultiFileLogger online at 20160831 19:32:05 in /builds/slave/test 19:32:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:32:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:32:05 INFO - {'append_to_log': False, 19:32:05 INFO - 'base_work_dir': '/builds/slave/test', 19:32:05 INFO - 'blob_upload_branch': 'Firefox', 19:32:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:32:05 INFO - 'branch': 'Firefox', 19:32:05 INFO - 'buildbot_json_path': 'buildprops.json', 19:32:05 INFO - 'config_files': ('talos/mac_config.py',), 19:32:05 INFO - 'default_actions': ('clobber', 19:32:05 INFO - 'read-buildbot-config', 19:32:05 INFO - 'download-and-extract', 19:32:05 INFO - 'populate-webroot', 19:32:05 INFO - 'create-virtualenv', 19:32:05 INFO - 'install', 19:32:05 INFO - 'run-tests'), 19:32:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:32:05 INFO - 'download_minidump_stackwalk': True, 19:32:05 INFO - 'download_symbols': 'ondemand', 19:32:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:32:05 INFO - 'tooltool.py': '/tools/tooltool.py', 19:32:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:32:05 INFO - '/tools/misc-python/virtualenv.py')}, 19:32:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:32:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:32:05 INFO - 'installer_path': 'installer.exe', 19:32:05 INFO - 'log_level': 'info', 19:32:05 INFO - 'log_name': 'talos', 19:32:05 INFO - 'log_to_console': True, 19:32:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:32:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:32:05 INFO - 'opt_config_files': (), 19:32:05 INFO - 'pip_index': False, 19:32:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:32:05 INFO - 'cmd': ('bash', 19:32:05 INFO - '-c', 19:32:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:32:05 INFO - 'enabled': True, 19:32:05 INFO - 'halt_on_failure': False, 19:32:05 INFO - 'name': 'check_screen_resolution'},), 19:32:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:32:05 INFO - 'cmd': ('bash', 19:32:05 INFO - '-c', 19:32:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:32:05 INFO - 'enabled': True, 19:32:05 INFO - 'halt_on_failure': False, 19:32:05 INFO - 'name': 'check_screen_resolution'},), 19:32:05 INFO - 'run_cmd_checks_enabled': True, 19:32:05 INFO - 'sps_profile': False, 19:32:05 INFO - 'sps_profile_interval': 0, 19:32:05 INFO - 'suite': 'dromaeojs-e10s', 19:32:05 INFO - 'system_bits': '32', 19:32:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:32:05 INFO - 'title': 't-yosemite-r7-0324', 19:32:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:32:05 INFO - 'use_talos_json': True, 19:32:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:32:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:32:05 INFO - 'work_dir': 'build'} 19:32:05 INFO - [mozharness: 2016-09-01 02:32:05.187375Z] Running clobber step. 19:32:05 INFO - Running pre-action listener: _resource_record_pre_action 19:32:05 INFO - Running main action method: clobber 19:32:05 INFO - rmtree: /builds/slave/test/build 19:32:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:32:07 INFO - Running post-action listener: _resource_record_post_action 19:32:07 INFO - [mozharness: 2016-09-01 02:32:07.228556Z] Finished clobber step (success) 19:32:07 INFO - [mozharness: 2016-09-01 02:32:07.228719Z] Running read-buildbot-config step. 19:32:07 INFO - Running pre-action listener: _resource_record_pre_action 19:32:07 INFO - Running main action method: read_buildbot_config 19:32:07 INFO - Using buildbot properties: 19:32:07 INFO - { 19:32:07 INFO - "project": "", 19:32:07 INFO - "product": "firefox", 19:32:07 INFO - "script_repo_revision": "production", 19:32:07 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:32:07 INFO - "repository": "", 19:32:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:32:07 INFO - "buildid": "20160831171120", 19:32:07 INFO - "pgo_build": "False", 19:32:07 INFO - "basedir": "/builds/slave/test", 19:32:07 INFO - "buildnumber": 56, 19:32:07 INFO - "slavename": "t-yosemite-r7-0324", 19:32:07 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 19:32:07 INFO - "platform": "yosemite_r7", 19:32:07 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:32:07 INFO - "branch": "mozilla-central", 19:32:07 INFO - "repo_path": "mozilla-central", 19:32:07 INFO - "revision": "9e00b305da24074ae5aba1227beb5e096b026100", 19:32:07 INFO - "stage_platform": "macosx64", 19:32:07 INFO - "builduid": "cff71ffda8a240359be28a872f8d8757", 19:32:07 INFO - "slavebuilddir": "test" 19:32:07 INFO - } 19:32:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 19:32:07 INFO - Running post-action listener: _resource_record_post_action 19:32:07 INFO - [mozharness: 2016-09-01 02:32:07.232187Z] Finished read-buildbot-config step (success) 19:32:07 INFO - [mozharness: 2016-09-01 02:32:07.232274Z] Running download-and-extract step. 19:32:07 INFO - Running pre-action listener: _resource_record_pre_action 19:32:07 INFO - Running main action method: download_and_extract 19:32:07 INFO - mkdir: /builds/slave/test/build/tests 19:32: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')]} 19:32:07 INFO - trying https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 19:32:07 INFO - Downloading https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:32:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 19:32:08 INFO - Downloaded 1199 bytes. 19:32:08 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:32:08 INFO - Using the following test package requirements: 19:32:08 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:32:08 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:32:08 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 19:32:08 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:32:08 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 19:32:08 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:32:08 INFO - u'jsshell-mac.zip'], 19:32:08 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:32:08 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 19:32:08 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:32:08 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:32:08 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 19:32:08 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:32:08 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 19:32:08 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:32:08 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 19:32:08 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:32:08 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 19:32:08 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:32: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')]} 19:32:08 INFO - trying https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:32:08 INFO - Downloading https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:32:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:32:09 INFO - Downloaded 16396640 bytes. 19:32:09 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:32:16 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:32:16 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')]} 19:32:16 INFO - trying https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:32:16 INFO - Downloading https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:32:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:32:17 INFO - Downloaded 16396640 bytes. 19:32:17 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:32: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')]} 19:32:23 INFO - trying https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:32:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:32:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:32:24 INFO - Downloaded 14413998 bytes. 19:32:24 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 19:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:32:25 INFO - trying https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:32:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 19:32:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 19:32:28 INFO - Downloaded 111577576 bytes. 19:32:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:32:28 INFO - mkdir: /builds/slave/test/properties 19:32:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:32:28 INFO - Writing to file /builds/slave/test/properties/build_url 19:32:28 INFO - Contents: 19:32:28 INFO - build_url:https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:32:28 INFO - Running post-action listener: _resource_record_post_action 19:32:28 INFO - Running post-action listener: set_extra_try_arguments 19:32:28 INFO - [mozharness: 2016-09-01 02:32:28.744223Z] Finished download-and-extract step (success) 19:32:28 INFO - [mozharness: 2016-09-01 02:32:28.744359Z] Running populate-webroot step. 19:32:28 INFO - Running pre-action listener: _resource_record_pre_action 19:32:28 INFO - Running main action method: populate_webroot 19:32:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:32:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:32:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:32:28 INFO - '--tppagecycles', 19:32:28 INFO - '7'], 19:32:28 INFO - 'tests': ['tsvgm']}}, 19:32:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:32:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:32:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:32:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:32:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:32:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:32:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:28 INFO - 'talos_options': ['--disable-e10s'], 19:32:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:32:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:32:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:28 INFO - 'talos_options': ['--disable-e10s'], 19:32:28 INFO - 'tests': ['damp', 'tps']}, 19:32:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:28 INFO - 'tests': ['damp', 'tps']}, 19:32:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:32:28 INFO - 'tests': ['dromaeo_dom']}, 19:32:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:32:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:32:28 INFO - 'tests': ['basic_compositor_video']}, 19:32:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:32:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:32:28 INFO - 'tests': ['a11yr', 19:32:28 INFO - 'ts_paint', 19:32:28 INFO - 'tpaint', 19:32:28 INFO - 'sessionrestore', 19:32:28 INFO - 'sessionrestore_no_auto_restore', 19:32:28 INFO - 'tabpaint']}, 19:32:28 INFO - 'other-e10s': {'tests': ['a11yr', 19:32:28 INFO - 'ts_paint', 19:32:28 INFO - 'tpaint', 19:32:28 INFO - 'sessionrestore', 19:32:28 INFO - 'sessionrestore_no_auto_restore', 19:32:28 INFO - 'tabpaint']}, 19:32:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:32:28 INFO - 'ts_paint', 19:32:28 INFO - 'tpaint', 19:32:28 INFO - 'sessionrestore', 19:32:28 INFO - 'sessionrestore_no_auto_restore', 19:32:28 INFO - 'tabpaint']}, 19:32:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:32:28 INFO - 'ts_paint', 19:32:28 INFO - 'tpaint', 19:32:28 INFO - 'sessionrestore', 19:32:28 INFO - 'sessionrestore_no_auto_restore', 19:32:28 INFO - 'tabpaint']}, 19:32:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:32:28 INFO - 'tests': ['a11yr', 19:32:28 INFO - 'ts_paint', 19:32:28 INFO - 'tpaint', 19:32:28 INFO - 'sessionrestore', 19:32:28 INFO - 'sessionrestore_no_auto_restore', 19:32:28 INFO - 'tabpaint']}, 19:32:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:32:28 INFO - 'tests': ['a11yr', 19:32:28 INFO - 'ts_paint', 19:32:28 INFO - 'tpaint', 19:32:28 INFO - 'sessionrestore', 19:32:28 INFO - 'sessionrestore_no_auto_restore', 19:32:28 INFO - 'tabpaint']}, 19:32:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:32:28 INFO - 'tests': ['tsvgx', 19:32:28 INFO - 'tsvgr_opacity', 19:32:28 INFO - 'tart', 19:32:28 INFO - 'tscrollx', 19:32:28 INFO - 'cart']}, 19:32:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:32:28 INFO - 'tsvgr_opacity', 19:32:28 INFO - 'tart', 19:32:28 INFO - 'tscrollx', 19:32:28 INFO - 'cart']}, 19:32:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:28 INFO - 'talos_options': ['--disable-e10s'], 19:32:28 INFO - 'tests': ['tp5o']}, 19:32:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:28 INFO - 'tests': ['tp5o']}, 19:32:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:28 INFO - 'talos_options': ['--disable-e10s', 19:32:28 INFO - '--xperf_path', 19:32:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:32:28 INFO - 'tests': ['tp5n']}, 19:32:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:28 INFO - 'talos_options': ['--xperf_path', 19:32:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:32:28 INFO - 'tests': ['tp5n']}}, 19:32:28 INFO - 'talos.zip': {'path': '', 19:32:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:32:28 INFO - Running post-action listener: _resource_record_post_action 19:32:28 INFO - [mozharness: 2016-09-01 02:32:28.763120Z] Finished populate-webroot step (success) 19:32:28 INFO - [mozharness: 2016-09-01 02:32:28.763251Z] Running create-virtualenv step. 19:32:28 INFO - Running pre-action listener: _resource_record_pre_action 19:32:28 INFO - Running main action method: create_virtualenv 19:32:28 INFO - Creating virtualenv /builds/slave/test/build/venv 19:32:28 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 19:32:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:32:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:32:28 INFO - Using real prefix '/tools/python27' 19:32:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:32:29 INFO - Installing distribute.............................................................................................................................................................................................done. 19:32:32 INFO - Installing pip.................done. 19:32:32 INFO - Return code: 0 19:32:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:32:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:32 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 0x101ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7c9d8e080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a14d30>, '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 0x101779db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a0cb20>, '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.yKMUC5qelw/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.VJB91QZNkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 19:32:32 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 19:32:32 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 19:32:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yKMUC5qelw/Render', 19:32:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:32 INFO - 'HOME': '/Users/cltbld', 19:32:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:32 INFO - 'LOGNAME': 'cltbld', 19:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:32 INFO - 'MOZ_NO_REMOTE': '1', 19:32:32 INFO - 'NO_EM_RESTART': '1', 19:32:32 INFO - 'PAGER': '/bin/cat', 19:32:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:32 INFO - 'PWD': '/builds/slave/test', 19:32:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:32 INFO - 'SHELL': '/bin/bash', 19:32:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJB91QZNkE/Listeners', 19:32:32 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 19:32:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:32 INFO - 'USER': 'cltbld', 19:32:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:32 INFO - 'XPC_FLAGS': '0x0', 19:32:32 INFO - 'XPC_SERVICE_NAME': '0', 19:32:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:32 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 19:32:32 INFO - Cleaning up... 19:32:32 INFO - Return code: 0 19:32:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:32:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:32 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 0x101ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7c9d8e080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a14d30>, '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 0x101779db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a0cb20>, '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.yKMUC5qelw/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.VJB91QZNkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 19:32:32 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 19:32:32 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 19:32:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yKMUC5qelw/Render', 19:32:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:32 INFO - 'HOME': '/Users/cltbld', 19:32:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:32 INFO - 'LOGNAME': 'cltbld', 19:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:32 INFO - 'MOZ_NO_REMOTE': '1', 19:32:32 INFO - 'NO_EM_RESTART': '1', 19:32:32 INFO - 'PAGER': '/bin/cat', 19:32:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:32 INFO - 'PWD': '/builds/slave/test', 19:32:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:32 INFO - 'SHELL': '/bin/bash', 19:32:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJB91QZNkE/Listeners', 19:32:32 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 19:32:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:32 INFO - 'USER': 'cltbld', 19:32:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:32 INFO - 'XPC_FLAGS': '0x0', 19:32:32 INFO - 'XPC_SERVICE_NAME': '0', 19:32:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:33 INFO - Downloading/unpacking psutil>=3.1.1 19:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:32:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:32:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:32:36 INFO - Installing collected packages: psutil 19:32:36 INFO - Running setup.py install for psutil 19:32:36 INFO - building 'psutil._psutil_osx' extension 19:32:36 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 19:32:36 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 19:32:37 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 19:32:37 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 19:32:37 INFO - building 'psutil._psutil_posix' extension 19:32:37 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 19:32:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:32:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:32:37 INFO - ^ 19:32:37 INFO - 1 warning generated. 19:32:37 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 19:32:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:32:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:32:37 INFO - Successfully installed psutil 19:32:37 INFO - Cleaning up... 19:32:37 INFO - Return code: 0 19:32:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:32:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7c9d8e080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a14d30>, '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 0x101779db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a0cb20>, '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.yKMUC5qelw/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.VJB91QZNkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 19:32:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:32:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:32:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yKMUC5qelw/Render', 19:32:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:37 INFO - 'HOME': '/Users/cltbld', 19:32:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:37 INFO - 'LOGNAME': 'cltbld', 19:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:37 INFO - 'MOZ_NO_REMOTE': '1', 19:32:37 INFO - 'NO_EM_RESTART': '1', 19:32:37 INFO - 'PAGER': '/bin/cat', 19:32:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:37 INFO - 'PWD': '/builds/slave/test', 19:32:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:37 INFO - 'SHELL': '/bin/bash', 19:32:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJB91QZNkE/Listeners', 19:32:37 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 19:32:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:37 INFO - 'USER': 'cltbld', 19:32:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:37 INFO - 'XPC_FLAGS': '0x0', 19:32:37 INFO - 'XPC_SERVICE_NAME': '0', 19:32:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:32:37 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 19:32:37 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 19:32:37 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 19:32:37 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 19:32:37 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 19:32:37 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 19:32:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:32:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:32:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:32:40 INFO - Installing collected packages: mozsystemmonitor 19:32:40 INFO - Running setup.py install for mozsystemmonitor 19:32:40 INFO - Successfully installed mozsystemmonitor 19:32:40 INFO - Cleaning up... 19:32:40 INFO - Return code: 0 19:32:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:32:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:32:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7c9d8e080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a14d30>, '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 0x101779db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a0cb20>, '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.yKMUC5qelw/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.VJB91QZNkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 19:32:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:32:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:32:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yKMUC5qelw/Render', 19:32:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:40 INFO - 'HOME': '/Users/cltbld', 19:32:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:40 INFO - 'LOGNAME': 'cltbld', 19:32:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:40 INFO - 'MOZ_NO_REMOTE': '1', 19:32:40 INFO - 'NO_EM_RESTART': '1', 19:32:40 INFO - 'PAGER': '/bin/cat', 19:32:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:40 INFO - 'PWD': '/builds/slave/test', 19:32:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:40 INFO - 'SHELL': '/bin/bash', 19:32:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJB91QZNkE/Listeners', 19:32:40 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 19:32:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:40 INFO - 'USER': 'cltbld', 19:32:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:40 INFO - 'XPC_FLAGS': '0x0', 19:32:40 INFO - 'XPC_SERVICE_NAME': '0', 19:32:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:40 INFO - Downloading/unpacking jsonschema==2.5.1 19:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:32:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:32:43 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 19:32:43 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 19:32:43 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 19:32:43 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 19:32:43 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 19:32:43 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 19:32:43 INFO - Downloading functools32-3.2.3-2.tar.gz 19:32:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:32:43 INFO - warning: no files found matching '*.txt' 19:32:43 INFO - no previously-included directories found matching 'build' 19:32:43 INFO - no previously-included directories found matching 'dist' 19:32:43 INFO - no previously-included directories found matching '.git*' 19:32:43 INFO - Installing collected packages: jsonschema, functools32 19:32:43 INFO - Running setup.py install for functools32 19:32:43 INFO - warning: no files found matching '*.txt' 19:32:43 INFO - no previously-included directories found matching 'build' 19:32:43 INFO - no previously-included directories found matching 'dist' 19:32:43 INFO - no previously-included directories found matching '.git*' 19:32:43 INFO - Successfully installed jsonschema functools32 19:32:43 INFO - Cleaning up... 19:32:43 INFO - Return code: 0 19:32:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:32:43 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')]} 19:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:32:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7c9d8e080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a14d30>, '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 0x101779db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a0cb20>, '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.yKMUC5qelw/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.VJB91QZNkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 19:32:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:32:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:32:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yKMUC5qelw/Render', 19:32:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:44 INFO - 'HOME': '/Users/cltbld', 19:32:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:44 INFO - 'LOGNAME': 'cltbld', 19:32:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:44 INFO - 'MOZ_NO_REMOTE': '1', 19:32:44 INFO - 'NO_EM_RESTART': '1', 19:32:44 INFO - 'PAGER': '/bin/cat', 19:32:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:44 INFO - 'PWD': '/builds/slave/test', 19:32:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:44 INFO - 'SHELL': '/bin/bash', 19:32:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJB91QZNkE/Listeners', 19:32:44 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 19:32:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:44 INFO - 'USER': 'cltbld', 19:32:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:44 INFO - 'XPC_FLAGS': '0x0', 19:32:44 INFO - 'XPC_SERVICE_NAME': '0', 19:32:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:32:44 INFO - Cleaning up... 19:32:44 INFO - Return code: 0 19:32:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:32:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:32:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7c9d8e080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a14d30>, '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 0x101779db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a0cb20>, '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.yKMUC5qelw/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.VJB91QZNkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 19:32:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:32:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:32:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yKMUC5qelw/Render', 19:32:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:44 INFO - 'HOME': '/Users/cltbld', 19:32:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:44 INFO - 'LOGNAME': 'cltbld', 19:32:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:44 INFO - 'MOZ_NO_REMOTE': '1', 19:32:44 INFO - 'NO_EM_RESTART': '1', 19:32:44 INFO - 'PAGER': '/bin/cat', 19:32:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:44 INFO - 'PWD': '/builds/slave/test', 19:32:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:44 INFO - 'SHELL': '/bin/bash', 19:32:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJB91QZNkE/Listeners', 19:32:44 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 19:32:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:44 INFO - 'USER': 'cltbld', 19:32:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:44 INFO - 'XPC_FLAGS': '0x0', 19:32:44 INFO - 'XPC_SERVICE_NAME': '0', 19:32:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:44 INFO - Downloading/unpacking blobuploader==1.2.4 19:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:46 INFO - Downloading blobuploader-1.2.4.tar.gz 19:32:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:32:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:32:47 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 19:32:47 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 19:32:47 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 19:32:47 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 19:32:47 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 19:32:47 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 19:32:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:32:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:32:47 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 19:32:47 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 19:32:47 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 19:32:47 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 19:32:47 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 19:32:47 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 19:32:48 INFO - Downloading docopt-0.6.1.tar.gz 19:32:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:32:48 INFO - Installing collected packages: blobuploader, requests, docopt 19:32:48 INFO - Running setup.py install for blobuploader 19:32:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:32:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:32:48 INFO - Running setup.py install for requests 19:32:48 INFO - Running setup.py install for docopt 19:32:49 INFO - Successfully installed blobuploader requests docopt 19:32:49 INFO - Cleaning up... 19:32:49 INFO - Return code: 0 19:32:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:32:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:32:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:49 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 0x101ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7c9d8e080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a14d30>, '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 0x101779db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a0cb20>, '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.yKMUC5qelw/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.VJB91QZNkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 19:32:49 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 19:32:49 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 19:32:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yKMUC5qelw/Render', 19:32:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:49 INFO - 'HOME': '/Users/cltbld', 19:32:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:49 INFO - 'LOGNAME': 'cltbld', 19:32:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:49 INFO - 'MOZ_NO_REMOTE': '1', 19:32:49 INFO - 'NO_EM_RESTART': '1', 19:32:49 INFO - 'PAGER': '/bin/cat', 19:32:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:49 INFO - 'PWD': '/builds/slave/test', 19:32:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:49 INFO - 'SHELL': '/bin/bash', 19:32:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJB91QZNkE/Listeners', 19:32:49 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 19:32:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:49 INFO - 'USER': 'cltbld', 19:32:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:49 INFO - 'XPC_FLAGS': '0x0', 19:32:49 INFO - 'XPC_SERVICE_NAME': '0', 19:32:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:32:49 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-Nd1ojr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:32:49 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-zFko52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:32:49 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-yVYg8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:32:49 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-3ybSlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:32:49 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-jKbZH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:32:49 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-Kw6x0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:32:49 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-rlsyG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:32:50 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-B0gfei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:32:50 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-UCDfMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:32:50 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-ozmQOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:32:50 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-3Khw5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:32:50 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-CbJvb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:32:50 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-mWxhLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:32:50 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-zsEsF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:32:50 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-2mEVB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:32:50 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-RYClcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:32:51 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-jXhhuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:32:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:32:51 INFO - Running setup.py install for manifestparser 19:32:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:32:51 INFO - Running setup.py install for mozcrash 19:32:51 INFO - Running setup.py install for mozdebug 19:32:51 INFO - Running setup.py install for mozdevice 19:32:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:32:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:32:51 INFO - Running setup.py install for mozfile 19:32:51 INFO - Running setup.py install for mozhttpd 19:32:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:32:51 INFO - Running setup.py install for mozinfo 19:32:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:32:52 INFO - Running setup.py install for mozInstall 19:32:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:32:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:32:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:32:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:32:52 INFO - Running setup.py install for mozleak 19:32:52 INFO - Running setup.py install for mozlog 19:32:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:32:52 INFO - Running setup.py install for moznetwork 19:32:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:32:52 INFO - Running setup.py install for mozprocess 19:32:52 INFO - Running setup.py install for mozprofile 19:32:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:32:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:32:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:32:53 INFO - Running setup.py install for mozrunner 19:32:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:32:53 INFO - Running setup.py install for mozscreenshot 19:32:53 INFO - Running setup.py install for moztest 19:32:53 INFO - Running setup.py install for mozversion 19:32:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:32:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:32:53 INFO - Cleaning up... 19:32:53 INFO - Return code: 0 19:32:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:32: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')]} 19:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32: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')]} 19:32:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7c9d8e080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a14d30>, '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 0x101779db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a0cb20>, '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.yKMUC5qelw/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.VJB91QZNkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 19:32:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:32:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:32:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yKMUC5qelw/Render', 19:32:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:54 INFO - 'HOME': '/Users/cltbld', 19:32:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:54 INFO - 'LOGNAME': 'cltbld', 19:32:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:54 INFO - 'MOZ_NO_REMOTE': '1', 19:32:54 INFO - 'NO_EM_RESTART': '1', 19:32:54 INFO - 'PAGER': '/bin/cat', 19:32:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:54 INFO - 'PWD': '/builds/slave/test', 19:32:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:54 INFO - 'SHELL': '/bin/bash', 19:32:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJB91QZNkE/Listeners', 19:32:54 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 19:32:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:54 INFO - 'USER': 'cltbld', 19:32:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:54 INFO - 'XPC_FLAGS': '0x0', 19:32:54 INFO - 'XPC_SERVICE_NAME': '0', 19:32:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:32:54 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-akRab7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:32:54 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)) 19:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:32:54 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-ZIIJXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 19:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:32:54 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-0s9QFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:32:54 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)) 19:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:32:54 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-ub2Ly5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 19:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:32:54 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-_k5i4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:32:54 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)) 19:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:32:54 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-GHASxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:32:54 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)) 19:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:32:54 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-Rx17Jw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:32:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-GW0Pqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:32:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-njVtrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:32:55 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)) 19:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:32:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-UmLiuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 19:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:32:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-hTZRlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:32:55 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)) 19:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:32:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-ZcdNCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 19:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:32:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-Mrq0Zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:32:55 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)) 19:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:32:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-7Uqiml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 19:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:32:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-P0LX3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:32:55 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)) 19:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:32:55 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-_S0NjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 19:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:32:56 INFO - Running setup.py (path:/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/pip-_Z6oEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:32:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:58 INFO - Downloading blessings-1.6.tar.gz 19:32:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:32:59 INFO - Installing collected packages: blessings 19:32:59 INFO - Running setup.py install for blessings 19:32:59 INFO - Successfully installed blessings 19:32:59 INFO - Cleaning up... 19:32:59 INFO - Return code: 0 19:32:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:32:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:32:59 INFO - Reading from file tmpfile_stdout 19:32:59 INFO - Current package versions: 19:32:59 INFO - blessings == 1.6 19:32:59 INFO - blobuploader == 1.2.4 19:32:59 INFO - docopt == 0.6.1 19:32:59 INFO - functools32 == 3.2.3-2 19:32:59 INFO - jsonschema == 2.5.1 19:32:59 INFO - manifestparser == 1.1 19:32:59 INFO - mozInstall == 1.12 19:32:59 INFO - mozcrash == 0.17 19:32:59 INFO - mozdebug == 0.1 19:32:59 INFO - mozdevice == 0.48 19:32:59 INFO - mozfile == 1.2 19:32:59 INFO - mozhttpd == 0.7 19:32:59 INFO - mozinfo == 0.9 19:32:59 INFO - mozleak == 0.1 19:32:59 INFO - mozlog == 3.3 19:32:59 INFO - moznetwork == 0.27 19:32:59 INFO - mozprocess == 0.23 19:32:59 INFO - mozprofile == 0.28 19:32:59 INFO - mozrunner == 6.12 19:32:59 INFO - mozscreenshot == 0.1 19:32:59 INFO - mozsystemmonitor == 0.3 19:32:59 INFO - moztest == 0.7 19:32:59 INFO - mozversion == 1.4 19:32:59 INFO - psutil == 3.1.1 19:32:59 INFO - requests == 1.2.3 19:32:59 INFO - wsgiref == 0.1.2 19:32:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7c9d8e080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a14d30>, '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 0x101779db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a0cb20>, '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.yKMUC5qelw/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.VJB91QZNkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 19:33:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:33:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yKMUC5qelw/Render', 19:33:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:33:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:33:00 INFO - 'HOME': '/Users/cltbld', 19:33:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:33:00 INFO - 'LOGNAME': 'cltbld', 19:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:00 INFO - 'MOZ_NO_REMOTE': '1', 19:33:00 INFO - 'NO_EM_RESTART': '1', 19:33:00 INFO - 'PAGER': '/bin/cat', 19:33:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:00 INFO - 'PWD': '/builds/slave/test', 19:33:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:33:00 INFO - 'SHELL': '/bin/bash', 19:33:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJB91QZNkE/Listeners', 19:33:00 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 19:33:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:33:00 INFO - 'USER': 'cltbld', 19:33:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:33:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:00 INFO - 'XPC_FLAGS': '0x0', 19:33:00 INFO - 'XPC_SERVICE_NAME': '0', 19:33:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:33:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:33:00 INFO - Cleaning up... 19:33:00 INFO - Return code: 0 19:33:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7c9d8e080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a14d30>, '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 0x101779db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a0cb20>, '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.yKMUC5qelw/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.VJB91QZNkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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 19:33:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:33:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yKMUC5qelw/Render', 19:33:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:33:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:33:00 INFO - 'HOME': '/Users/cltbld', 19:33:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:33:00 INFO - 'LOGNAME': 'cltbld', 19:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:00 INFO - 'MOZ_NO_REMOTE': '1', 19:33:00 INFO - 'NO_EM_RESTART': '1', 19:33:00 INFO - 'PAGER': '/bin/cat', 19:33:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:00 INFO - 'PWD': '/builds/slave/test', 19:33:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:33:00 INFO - 'SHELL': '/bin/bash', 19:33:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJB91QZNkE/Listeners', 19:33:00 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 19:33:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:33:00 INFO - 'USER': 'cltbld', 19:33:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:33:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:00 INFO - 'XPC_FLAGS': '0x0', 19:33:00 INFO - 'XPC_SERVICE_NAME': '0', 19:33:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:33:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:33:00 INFO - Cleaning up... 19:33:00 INFO - Return code: 0 19:33:00 INFO - Running post-action listener: _resource_record_post_action 19:33:00 INFO - Running post-action listener: _start_resource_monitoring 19:33:00 INFO - Starting resource monitoring. 19:33:00 INFO - [mozharness: 2016-09-01 02:33:00.574449Z] Finished create-virtualenv step (success) 19:33:00 INFO - [mozharness: 2016-09-01 02:33:00.575438Z] Running install step. 19:33:00 INFO - Running pre-action listener: _resource_record_pre_action 19:33:00 INFO - Running main action method: install 19:33:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:33:00 INFO - Reading from file tmpfile_stdout 19:33:00 INFO - Detecting whether we're running mozinstall >=1.0... 19:33:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:33:00 INFO - Reading from file tmpfile_stdout 19:33:00 INFO - Output received: 19:33:00 INFO - Usage: mozinstall [options] installer 19:33:00 INFO - Options: 19:33:00 INFO - -h, --help show this help message and exit 19:33:00 INFO - -d DEST, --destination=DEST 19:33:00 INFO - Directory to install application into. [default: 19:33:00 INFO - "/builds/slave/test"] 19:33:00 INFO - --app=APP Application being installed. [default: firefox] 19:33:00 INFO - mkdir: /builds/slave/test/build/application 19:33:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:33:35 INFO - Reading from file tmpfile_stdout 19:33:35 INFO - Output received: 19:33:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:33:35 INFO - Running post-action listener: _resource_record_post_action 19:33:35 INFO - [mozharness: 2016-09-01 02:33:35.068545Z] Finished install step (success) 19:33:35 INFO - [mozharness: 2016-09-01 02:33:35.068700Z] Running run-tests step. 19:33:35 INFO - Running pre-action listener: _resource_record_pre_action 19:33:35 INFO - Running main action method: run_tests 19:33:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:33:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:33:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:33:35 INFO - 2016-08-31 19:33:35.083 screenresolution[1640:7469] starting screenresolution argv=screenresolution get 19:33:35 INFO - 2016-08-31 19:33:35.085 screenresolution[1640:7469] Display 0: 1600x1200x32@60 19:33:35 INFO - 2016-08-31 19:33:35.091 screenresolution[1641:7471] starting screenresolution argv=screenresolution list 19:33:35 INFO - Available Modes on Display 0 19:33:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:33:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:33:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:33:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:33:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:33:35 INFO - Intel Iris: 19:33:35 INFO - Chipset Model: Intel Iris 19:33:35 INFO - Type: GPU 19:33:35 INFO - Bus: Built-In 19:33:35 INFO - VRAM (Dynamic, Max): 1536 MB 19:33:35 INFO - Vendor: Intel (0x8086) 19:33:35 INFO - Device ID: 0x0a2e 19:33:35 INFO - Revision ID: 0x0009 19:33:35 INFO - Displays: 19:33:35 INFO - Display: 19:33:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:33:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:33:35 INFO - Display Serial Number: 1600x1200 60 19:33:35 INFO - Main Display: Yes 19:33:35 INFO - Mirror: Off 19:33:35 INFO - Online: Yes 19:33:35 INFO - Rotation: Supported 19:33:35 INFO - Return code: 0 19:33:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:33:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:33:35 INFO - Python 2.7.3 19:33:35 INFO - Return code: 0 19:33:35 INFO - grabbing minidump binary from tooltool 19:33: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')]} 19:33:35 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 0x101a14d30>, '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 0x101779db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a0cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:33:35 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 19:33:35 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 19:33:35 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 19:33:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:33:35 INFO - Return code: 0 19:33:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:33:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:33:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:33:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:33:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:33:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:33:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0324 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:33:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yKMUC5qelw/Render', 19:33:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:33:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:33:35 INFO - 'HOME': '/Users/cltbld', 19:33:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:33:35 INFO - 'LOGNAME': 'cltbld', 19:33:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:33:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:33:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:35 INFO - 'MOZ_NO_REMOTE': '1', 19:33:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:33:35 INFO - 'NO_EM_RESTART': '1', 19:33:35 INFO - 'PAGER': '/bin/cat', 19:33:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:33:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:35 INFO - 'PWD': '/builds/slave/test', 19:33:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:33:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:33:35 INFO - 'SHELL': '/bin/bash', 19:33:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VJB91QZNkE/Listeners', 19:33:35 INFO - 'TMPDIR': '/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/', 19:33:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:33:35 INFO - 'USER': 'cltbld', 19:33:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:33:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:33:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:35 INFO - 'XPC_FLAGS': '0x0', 19:33:35 INFO - 'XPC_SERVICE_NAME': '0', 19:33:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:33:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:33:35 INFO - mozversion application_buildid: 20160831171120 19:33:35 INFO - mozversion application_changeset: 9e00b305da24074ae5aba1227beb5e096b026100 19:33:35 INFO - mozversion application_display_name: Nightly 19:33:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:33:35 INFO - mozversion application_name: Firefox 19:33:35 INFO - mozversion application_remotingname: firefox 19:33:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:33:35 INFO - mozversion application_vendor: Mozilla 19:33:35 INFO - mozversion application_version: 51.0a1 19:33:35 INFO - mozversion platform_buildid: 20160831171120 19:33:35 INFO - mozversion platform_changeset: 9e00b305da24074ae5aba1227beb5e096b026100 19:33:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:33:35 INFO - mozversion platform_version: 51.0a1 19:33:35 INFO - using testdate: 1472697215 19:33:35 INFO - actual date: 1472697215 19:33:35 INFO - starting webserver on 'localhost:49267' 19:33:35 INFO - SUITE-START | Running 2 tests 19:33:35 INFO - TEST-START | dromaeo_css 19:33:35 INFO - operating with platform_type : mac_ 19:33:35 INFO - Initialising browser for dromaeo_css test... 19:33:35 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/tmpfIv4XI/profile http://localhost:49267/getInfo.html) 19:33:38 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 19:33:38 INFO - PROCESS | 1651 | colorDepth:24 19:33:38 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 19:33:38 INFO - PROCESS | 1651 | __metrics 19:33:38 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 19:33:39 INFO - TEST-INFO | 1651: exit 0 19:33:39 INFO - Browser initialized. 19:33:39 INFO - Running cycle 1/1 for dromaeo_css test... 19:33:39 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/tmpfIv4XI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:33:51 INFO - PROCESS | 1654 | RSS: Main: 243732480 19:33:51 INFO - PROCESS | 1654 | 19:34:41 INFO - PROCESS | 1654 | 1472697281078 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:36:53 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 19:36:54 INFO - PROCESS | 1654 | RSS: Main: 263700480 19:36:54 INFO - PROCESS | 1654 | 19:39:57 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 19:39:57 INFO - PROCESS | 1654 | RSS: Main: 270438400 19:39:57 INFO - PROCESS | 1654 | 19:42:59 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 19:43:00 INFO - PROCESS | 1654 | RSS: Main: 272023552 19:43:00 INFO - PROCESS | 1654 | 19:46:01 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 19:46:02 INFO - PROCESS | 1654 | RSS: Main: 269385728 19:46:02 INFO - PROCESS | 1654 | 19:49:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 19:49:05 INFO - PROCESS | 1654 | RSS: Main: 274583552 19:49:05 INFO - PROCESS | 1654 | 19:52:08 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 19:52:08 INFO - PROCESS | 1654 | RSS: Main: 277344256 19:52:08 INFO - PROCESS | 1654 | 19:52:08 INFO - PROCESS | 1654 | __start_tp_report 19:52:08 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 19:52:08 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 19:52:08 INFO - PROCESS | 1654 | |i|pagename|runs| 19:52:08 INFO - PROCESS | 1654 | |0;dojo.html;3367.8147701876396;3398.49022646603;3411.4541673332274;3415.992282276728;3437.2953544523375;4726.527347265272;5267.709411194258;5654.067078931971;5800.622959507629;5830.475257226849;5781.13283007549;5835.474807267345;5857.3849745776715;5892.14563888236;5893.263342082239;5202.089634313989;5250.8687282817855;5262.81580144695;5344.118220493614;5367.9800837840885;5321.255024296583;5337.172738225562;5371.140617501201;5380.24676545284;5403;5066.898662026757;5153.381594208681;5215.6609820361555;5280.3663560372615;5310.893782124336;4556.54434556545;5038.823641172578;5151.304573882516;5180.792768289264;5204.583633309327;5265.131253343194;5293.602979776532;5294.973525132388;5302.522772950414;5320.255164277004;5619.438056194348;5706.201131841545;5786.652800831921;5828.446297601721;5834.474897259267;6163;6232.532560058014;6232.563720539564;6251;6304.7793327233785;6129.328227221994;6194.782868674771;6229.408206220402;6242.719077641517;6256.468200202989;5854.736536855852;5928.229330187048;5944.197533333031;5962.672053037125;6029.939700602938;6241.095041218998;6266.780662676784;6273.247210334698;6274.027525733519;6277.874442511215;4752.714837109785;4918.409790825124;5020.698758074527;6080.422359875804;6209.9068513972325;5073.365829271341;6187.659678717714;6234.0337247726675;6269.996800511896;6272.184615999982;5503.229547863302;5967.880642387128;6186.072089186568;6212.9378706213265;6264;4961.8511444656715;5466.398696143421;6153.630782153085;6192.628442293477;6214.347493513206;4221.852235171754;4323.156984388014;4969.378827646544;4990.226514890198;5028.270900719448;3872.6901847852105;4313.439252897103;4449.1101779644205;4457.576530229623;4488.102379524043;5723.284589426322;5822.010258256107;5831.096180092092;5833.708314584339;5871.266091738533;6134.222293197073;6152.692365381713;6155.84610384735;6162;6178.042403427476;5835.183074369592;5851.795187168514;5863.208466857005;5871.706414679249;5897.439743224472;5378.031954248195;5514.6691198528215;5520.530754885799;5533.584981126432;5537.086380747212;6227.657478838707;6250.1874756281395;6296.653684047421;6376.139221205078;6389.680515974275;6203.286622038523;6211.2857021441305;6220.502359811295;6241.937580624136;6254.9687251563455;6208.068789681584;6367.490600751836;6430.7749228776765;6435.678216089271;6437.0666253392055;6138.572327483795;6141.762184092961;6149;6220.595661282002;6232.844178895382;6209.099680546386;6215.440610345091;6220.626762394271;6221.564490485623;6225.190725205875;5726.341470730753;6074.969625151846;6235.251769787655;6237.033259844731;6262.46769024638;6060.454559089703;6111.310664112268;6121.938780612137;6143.72353244114;6163.260408750979;5865.853353666022;6003.90994135097;6046.939530604814;6053.213082299273;6091.086337049478;5432;6087.512998960161;6180.258368995749;6194;6211.254649442096;6020.036794113096;6024.3975602439405;6064.06007068905;6064.818055458343;6143.692815359124 19:52:08 INFO - PROCESS | 1654 | |1;ext.html;457.18392669085665;471.2601216090738;491.4986713552177;509.54270785553337;556.379636705074;29880.999999999975;40225.59774402259;40263.79208623739;40340;40563.39154912675;40981.180376392454;41396.58603413965;41454.37818432726;41460;41547.58452415482;9569.3779904306;9751.97604251554;9760.267979901535;9773.657921972732;9789.804203915917;9694.17599504043;9802.950985245065;9808.411495310269;9817.901820981806;9819.852702209473;9773.267004974656;9800.705978820612;9820.11618954294;9824;9866.506674666274;9773;9782.266330025277;9782.413055216639;9789.31474796761;9810.96984816591;7472.850542989164;9090.727278181665;9669.70990870275;9756.365836220693;9768.90231097687;39412.605873941466;39737.40393894094;39882.002949926195;40016.59983400187;40129.19741605181;3456.412409890325;3481.54739883816;3532.275883443888;3904.921901561953;3969.8809035728973;30470.390592188254;31150;31302.373952520822;31418.37163256722;31581.8420907894;3257.7393808495262;3285.0637568292923;3289.0297362278184;3352.3127758809387;3402.9829850850833;468000.65999670123;477291;480962;481818;482353;32875.1781205467;49020.03919843167;53981.46018539764;56279.718601406734;56401.43598564044;33787.66212337894;34358.656413436045;34704;34735.82632086874;34902.30195396129;14526.418943242363;14562.199079050753;14668.853311466748;14693.265336733335;14717.779233311509;12378.133530652767;12539.874601254052;12571.120021598583;12600.11799174049;12605.865472107555;7461.552306861606;7636.885446718304;7733.381329493627;7739.993800805972;7768.2620151086685;3200.919977000547;3235.7411407087375;3243.659415761358;3244.918877028046;3279.7704160709;785.3206975965749;945.4516380499294;959.8608201810698;968.2641192693603;991.0832479956011;29474.852625736734;29492.115236542937;29911.40177196444;30572.84713576418;30624.92812751543;996.2926322311096;1009.0464511037146;1035.9689209323733;1037.097724979273;1069.3476979042778;14536.418543258364;15145.924270378577;15351.385944562093;15646.9217653911;15980.600484987968;164000;757875;769653;770801;957851;57629;57643;58130.70934645384;58381.70809146012;58412.70793646004;1349.0961056092442;1371.4994027180207;1390.6870954035262;1391.2139641102744;1394.6234516680236;25751.6137257945;30179.396412072074;30740.385192296475;30999.760009599362;32937.50593741048;55534.61163470784;56336;56446.43553564576;57109;57192.42807571871;582554;592089;592573;593340;597330;42255.366169506866;42323;42437.36343954905;42601.36097958471;42812.57187428086;38930.80534597309;46282.76858615685;47020.7648961753;47489;47655.28517072177;39259.60740392674;39277.80361098176;39285.2142957145;39956.40065399079;40083.398749018204;31833.522497163023;38778;39079.60920390873;39810.601893981846;40483.79758101191;38301.61698382981;38684.6131538681;39574.80212599034;39777.80111099426;41502.792486037375;30768.538471922493;30903;34420.13949651179;35133.648663513035;35207;25038.116380359177;25380;25721.742782571935;25815.096471623405;26107.73892261129 19:52:08 INFO - PROCESS | 1654 | |2;jquery.html;327.4482496992569;340.97954122752634;368.4767629965449;375.2888276715625;398.962098600633;19536.41390758276;20244.291449799242;20701.896490517527;21026.579468410622;21162.89418552905;16727.581810454714;17236.913815430937;20168.39494815154;20773.79226207741;20915.37253882382;5576.944230557703;5772.711364431782;5819.67991760453;5864.4135586441225;6062.605930614518;5896.292444906596;6060.636361818283;6069.544784141185;6073.301570319403;6083.695815209222;5703.8574035649235;5922.851928701773;5958.821235362924;6033.27600687918;6034.758609655631;5924.0521516557355;5957.642541447527;5979.820605381846;5987.64074155552;6004.099385092228;5038.370203724535;5064.645474816764;5200.349956255468;5206.505381988705;5213.921791173136;24652.630210546857;24658.876705616356;25233.62149567758;25577.59323237221;25791.097311594003;1944.9027548622512;2288.039023610088;2330.265966220652;2612.9477410451686;2993.4162838246552;10108.595656173744;10176.694699159038;12385;14794;15696.13671248081;1872.616113696689;1904.8190421909896;1906.675865102922;1924.191839427444;1983.0580474274748;119057;119888.40055799752;127472.36263818714;128042.71957280401;128396;8976.8653470198;10943.343399396063;11440.828387574193;11512.769744605228;11583.305001699924;14119.78820317696;14142.36359363831;14143.222122783345;14146.434142634385;14150.29248537569;7060.364567188978;8239.258466737903;8514.70198543048;8520.446170998865;8563.443376180652;8565.52889591066;8576.699815506427;8592.613332400057;8610.612522436506;8721.781955451164;3907.257621051991;4435.090806384684;4437.889052773707;4548.2722764358205;4572.08558288337;1633.444628826181;1802.9008404537876;1918.0723373010035;1951.6096780643927;1953.98046019538;709.7862654860144;893.8525143351275;928.1368327455531;953.8791919494566;958.4441024205944;17961.820381796275;19905;20724.274650387164;20899.68650470244;20941.16823994438;898.9910100899039;928.5635751196927;936;954.6992697300328;987.3434166279344;8837;10501.737456563647;11050.115990720724;11053.17101217412;11270.605528806453;557959;561309;561754;569892;602812;89896.10103898877;90769.73075673162;116990.41504792421;125155;130929.69070309175;886.3219636977717;992.93545919515;1016.2987538598492;1044.2064031336088;1048.491481631408;18496.537586560444;18820.905895470434;18898;18963.33628323002;19005.809941900403;52966.735166323924;55184.620384489106;55457.72271138619;55911.44088559225;56564.43435565592;79119;79733;80847;81171.18828811636;82144;7720.806979825325;7794.142644309008;7921.762347129596;7928.801779955547;7976.9202307976175;10504.842427363748;12097.334646594521;12433.502659893504;12436.440360183633;12443.128980971245;8264.710735124483;10245.487725613839;10580.36517808934;10745.086667633026;10766.300190487593;10254.230932680228;10270.178385728974;10397.220208484545;10466.581336746749;10597;10646.414447205478;10844.511996960313;10937.960893715084;10963.232573719763;10972.835407468734;7681.692732290646;7807.531548107132;7839.882401764092;7918.722844700408;7983.2415920487465;9149.31380146505;9942.403455792675;10097.5456104474;10721.03510684011;10827.458627068772 19:52:08 INFO - PROCESS | 1654 | |3;mootools.html;1509.1020842598653;1544.1275679241228;1735.72228443449;1767.2842498787995;1873.3630565607707;7965.44241903067;8469.534175620338;8562.357823163273;9011.188992990637;9048.547572621368;8670.43642163259;8786.780330491742;8792.78018049549;8820.514871682071;8826.691065812698;2071.275053731193;2380.1907351500486;2385.3321070100315;2388.904443822245;2397.592409290416;2352.8235382346306;2369.869657168855;2407.7953373963232;2409.084547871806;2416.214730212679;1975.4666240115232;2356.2813341930705;2374.3114496795906;2378.3459548624096;2384.6065399209197;2343.3438637181616;2345.308134100436;2363.7872591466767;2367.6922000140044;2384.7972922301624;2350.0247397330086;2363.9527209455714;2366.2309749331534;2366.6804981327473;2367.988160059189;8751.212390884788;8803.295736341077;8880.579107342859;8889.86665200016;8902.777430564223;4194.916101677949;4705.670603057787;5236.109861323546;5323.760430780624;5329.786808527654;3443.6039855416448;3564.0733409313507;3628.9455658165148;3643;3647.0882279430143;1048.5176818663347;1073.7637719701731;1088.0860077534908;1094.370736826328;1099.3458891959351;9803.31376803624;10091.49542522871;10611.893881061167;11661.416929153509;11719.062475001894;4534.727916325031;4617.007343421181;4823.445303790109;4846.80612775485;4869.780859861315;9796.804063918824;9854.704358869245;9856.5564549594;9873.851892221623;9880.703578892644;4656.905627177611;5271.1829666401;5291.682499050069;5292.338457692788;5312;4401.207782599163;4720.598749106296;4724.527547245317;4864.0028794097125;4905.730184839868;3379.5268662387284;3827.598102199229;3923.7645741255615;3968.722189446711;4019.376996565537;1186.2230239193343;1247.1706315300405;1323.2722002898367;1336.2984433172662;1343.543195313598;852.4799872078028;910.4127837544746;913.255696607263;916.4180745226829;954.8758661373977;5706.857328566819;5976.282846058414;6009.188759517497;6046.848828779228;6047.879042419127;439.49019137799996;460.26587592789446;460.78112896373955;466.20279322359147;468.70705808869457;3957.109650328653;3965.8810235692977;4065.89835254121;4076.8776936691947;4092.4066010428323;10589.682309530726;10808.459577021275;10902.563897444013;10942.835857462145;10952.835707464394;10675;10705.1435885129;10840.403777792297;10892;10900.618478353219;8094.919050809416;8096.02797008307;8129.471584347117;8264.793380165544;8307.750767476864;5725.51333136688;5833.1250312451775;5866.765329386777;5895.469407753323;5905.379935106924;8434.451760635538;10066.245031622804;10155.66419723453;10166.694999149742;10182.592696292066;9014.684486042957;9329.740485034434;10157.695269141643;10387.68836934893;10390.480475976323;7130.358267755927;7354.705811767683;7383.224761399975;7391.741289054858;7581.393488521016;8339.54132522717;8442.788930276793;8455.74632761018;8473.91526084756;8590.613422395882;7362.678424631856;7517.699292028257;7586.848263034598;7606.619669016638;7624.58064806441;7273.3453989141235;7328.413726901728;7423.072115985502;7432.108147022392;7470.178280389261;6986.790396288119;7007;7052.0832291801735;7080.468964827762;7106.893396599158;4961.107000739901;5002.599792016703;5052.823151189691;5075.11185542536;5128.102582048052;5011.498850114959;5033.421156567042;5052.9747351263;5079.314292570456;5224.399194092728 19:52:08 INFO - PROCESS | 1654 | |4;prototype.html;295.59651076280875;304.9878004879805;311.71010959807376;315.60706919884734;318.03793524588133;5240.711760853152;5517.2551705519745;5605.887882242358;5617.297837770278;5677.57418193635;4485.349624304474;4822.529128615771;5706.600537962344;5710.800121995731;5726.942730572693;3501.7373696972827;4315.417418648473;4331.180904020319;4338.594203550214;4434.312681534368;4145.79271036447;4258.616724494795;4305.978470107645;4325.589069038437;4344.608985191332;4222.767747773872;4228.640565551932;4235.301175306071;4258.9148217035645;4259.019555906503;4246.681498887596;4252.319628859368;4257.6806739494505;4267.466566679165;4268.637165840907;2873.6264285642733;2986.686397928208;3432.0608855996534;3602.261536385061;3682.9263414731554;4991.627302491844;5405.351357837046;5491.450854914477;5565.443455654402;5575.414581468968;4401.427814384142;4584.381108550369;4830.492798256168;4855.514448555116;4861.197902346109;5441.5102640762525;5718.71406429681;5897.849919265745;6010.549208809357;6019.0738963531685;4006.6994975376965;4006.9146756996947;4017.0359113812765;4019.336809426441;4022.8591999279884;5679.211048519703;5840.036393994986;6332.810015699536;6334.081558174085;6394.712237949257;3914.256291304643;3997.6601988831276;4011.8796436106963;4023;4046.2312160689376;4063.3904914262507;4102.9895351277655;4113.321301985199;4124.360724087771;4140.3375459926265;4837.830675926396;4883.682560633548;4886.364772579542;4895.04546613407;4895.485973972753;5905.114232865105;6074.717324286367;6088.178095956989;6090.756369745161;6142.508599312045;1180.5218886351042;1274.9099519910528;1338.297393868227;1359.6335682638885;1388.02838013389;892.6340200517757;893.9418937769025;930.1675000874179;939.8872135343665;942.6182396129578;4339.784860239075;5449.509544141047;5599;5604.047311956977;5610.49505544503;5448.073827449343;5620.775168993195;6104.938950610526;6131.570790044743;6185.195924529782;5244.5279924806955;5378.542823859937;5526.309211348582;5595.71298601324;5597.80407685729;2200.8019278265033;2373.5964885969083;2494.975050249511;2560.2831207261997;2755.3249453883527;4492.236319825637;4810.855674329776;5044.747762611854;5411;5445.401005889349;4489;5156.252343410185;5256.421793602701;5305;5337.733113344395;4510.902679971611;4734.928976065362;4783.971446139097;4834.419869615598;4887.31197841193;4347.673924455742;4395.901024743814;4829.27560865873;4877.926955965756;5103.87240318995;4021.155557332993;4268.740721487106;4351.912961740811;4392.386530014636;5044.949550504448;5279.310620601371;5358;5363.624546281723;5391.137418013099;5417.647852889707;3210.855511502035;3250.724492855058;3485.89542313731;3487.4245749451056;3535.2929414116766;4836.347093142381;4949.564626258345;4982.800687972585;5115.386153661584;5223;4531.773411329486;4580.519045500175;4957.454679985271;4983.75081245929;5052.9747351263;4077.5922407758985;4325.782362196958;4577.382053422745;4758.8096476141955;4815.927761083657;4563.269876819693;4859.465458799458;4886.87601851569;4921.507849215193;5912.201852749996;5253.080710875505;5780.364159942319;6029.577929545065;6072.817815465543;6141.600795948428;4937;5302.893942121059;5712.428757124254;5768.682722450306;5939.7921072763975 19:52:08 INFO - PROCESS | 1654 | |5;yui.html;720.2473415281031;723.2622724820682;759.1914610939349;765.3800421658457;806.1696452653765;15026.323815428304;15726.449574264903;16068.27692753826;16084;16140.757888631651;14326.355314010869;14490.058146220488;14738.263086845669;14742.557723268292;14777.778333325008;861.5003298087125;880.3485420788602;881.3478026260585;885.8272636835819;886.2555453419127;676.7935779587224;700.0968750312092;875.4528419737663;887;888.0231745080424;918.9770255743626;930.0606387548557;933.0762545080389;935.7707361696393;937.4609599480292;913.2556966072663;926.1340646495548;927.1609193679697;932.4684929590136;937.7093101138635;665.9567128136656;691.8616276744672;696.4428457234243;697.166885571742;698.2075344483997;14717.926410367987;14728.55814325572;14739.336729847182;14760.926195369062;14772;14316.21260830654;14333.928330358385;14413.855861441461;14446.783298250535;14781.778273325908;11086.501107450184;11293.717657058558;11394.658160255205;11653.766924661544;11745.354005529693;3802.9429558556644;3819.2552452271816;3831.63599458051;3848.5766565677754;3943.684505239574;92026.5398673009;96620.51689741577;96843;119910.40044799808;120354.3982280083;9278.443293402417;10168.745781355525;10654.89345106539;10903.29128606638;11030.834537481944;20847.895760521405;21105.26131585387;21134.577308453743;21137.365879023346;21192.258270960443;7812.570039695468;7881.921180788233;7894.526328420313;7895.092064412551;8211.507309561446;4585.426821647294;4616.976915115471;4627.583517483443;4676.3453116563705;4784.712917224978;3618.819059047037;3701.463287823304;3769.3403654360377;3776.773393596393;3781.697464202857;620.4385031546458;682.3006418421058;690.1545606631936;706.060938951204;706.6466766616692;519.4364114935262;526.1345087331395;606.5753972219464;607.875385545962;663.4659099424913;9457.479838608942;9461.488201818476;9478.952605237067;9594.088561586637;9696.660616878517;641.682367228225;672.5964421347251;674.1404708995972;688.4458011300951;703.3002162847897;3748.850045998184;4441.844535441309;4586.908261834745;4602.619214235716;4709.835155769531;152749.8900044008;155119;157778;161305;164077.17961410116;119035.40482297706;135765;138212.61787381998;141911;143270.2836485811;119526;121086.39456802837;121795.39102304431;122310;122492.38753806351;8871.467711937305;8899.866502002606;8983.46099234048;9024.300405172587;9031.593578288861;66708.6664566674;66940;68853.96719049118;69182;69412.223511059;85384;88532;94594.52702736443;96818;102936;12975.870241297465;13015.739685206054;13120.475180993035;13230.338483075615;13858.792118118035;13476.326183690971;13698;14592.416303348173;15085.924570377078;15086.622834429227;13497.693337963427;13574.796378054534;13855.445782168601;13934.721305573628;14150.504732334319;13812.861871381157;13842.377093030636;13871.861281387057;14017.948653850797;14138.363773630006;13314.46742130304;13629.727405452033;13795.172289662652;13814.65463363424;13901.852918395754;9191.402558833654;11263.078813690068;11269.436528173723;11384.943075284571;11494.54021839117;9834.510172081275;10556.313839600401;11341.583812838557;11513.366764828015;11694.356810375511 19:52:08 INFO - PROCESS | 1654 | __end_tp_report 19:52:08 INFO - PROCESS | 1654 | __start_cc_report 19:52:08 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,377 19:52:08 INFO - PROCESS | 1654 | __end_cc_report 19:52:08 INFO - PROCESS | 1654 | __startTimestamp1472698328718__endTimestamp 19:52:08 INFO - PROCESS | 1654 | 19:52:08 INFO - PROCESS | 1654 | ------- Summary: start ------- 19:52:08 INFO - PROCESS | 1654 | Number of tests: 6 19:52:08 INFO - PROCESS | 1654 | 19:52:08 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5712.61 Median:5944.20 stddev:669.95 (11.3%) stddev-sans-first:646.26 19:52:08 INFO - PROCESS | 1654 | Values: 3367.8 3398.5 3411.5 3416.0 3437.3 4726.5 5267.7 5654.1 5800.6 5830.5 5781.1 5835.5 5857.4 5892.1 5893.3 5202.1 5250.9 5262.8 5344.1 5368.0 5321.3 5337.2 5371.1 5380.2 5403.0 5066.9 5153.4 5215.7 5280.4 5310.9 4556.5 5038.8 5151.3 5180.8 5204.6 5265.1 5293.6 5295.0 5302.5 5320.3 5619.4 5706.2 5786.7 5828.4 5834.5 6163.0 6232.5 6232.6 6251.0 6304.8 6129.3 6194.8 6229.4 6242.7 6256.5 5854.7 5928.2 5944.2 5962.7 6029.9 6241.1 6266.8 6273.2 6274.0 6277.9 4752.7 4918.4 5020.7 6080.4 6209.9 5073.4 6187.7 6234.0 6270.0 6272.2 5503.2 5967.9 6186.1 6212.9 6264.0 4961.9 5466.4 6153.6 6192.6 6214.3 4221.9 4323.2 4969.4 4990.2 5028.3 3872.7 4313.4 4449.1 4457.6 4488.1 5723.3 5822.0 5831.1 5833.7 5871.3 6134.2 6152.7 6155.8 6162.0 6178.0 5835.2 5851.8 5863.2 5871.7 5897.4 5378.0 5514.7 5520.5 5533.6 5537.1 6227.7 6250.2 6296.7 6376.1 6389.7 6203.3 6211.3 6220.5 6241.9 6255.0 6208.1 6367.5 6430.8 6435.7 6437.1 6138.6 6141.8 6149.0 6220.6 6232.8 6209.1 6215.4 6220.6 6221.6 6225.2 5726.3 6075.0 6235.3 6237.0 6262.5 6060.5 6111.3 6121.9 6143.7 6163.3 5865.9 6003.9 6046.9 6053.2 6091.1 5432.0 6087.5 6180.3 6194.0 6211.3 6020.0 6024.4 6064.1 6064.8 6143.7 19:52:08 INFO - PROCESS | 1654 | 19:52:08 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:70548.87 Median:40177.40 stddev:164582.93 (409.6%) stddev-sans-first:164960.49 19:52:08 INFO - PROCESS | 1654 | Values: 457.2 471.3 491.5 509.5 556.4 29881.0 40225.6 40263.8 40340.0 40563.4 40981.2 41396.6 41454.4 41460.0 41547.6 9569.4 9752.0 9760.3 9773.7 9789.8 9694.2 9803.0 9808.4 9817.9 9819.9 9773.3 9800.7 9820.1 9824.0 9866.5 9773.0 9782.3 9782.4 9789.3 9811.0 7472.9 9090.7 9669.7 9756.4 9768.9 39412.6 39737.4 39882.0 40016.6 40129.2 3456.4 3481.5 3532.3 3904.9 3969.9 30470.4 31150.0 31302.4 31418.4 31581.8 3257.7 3285.1 3289.0 3352.3 3403.0 468000.7 477291.0 480962.0 481818.0 482353.0 32875.2 49020.0 53981.5 56279.7 56401.4 33787.7 34358.7 34704.0 34735.8 34902.3 14526.4 14562.2 14668.9 14693.3 14717.8 12378.1 12539.9 12571.1 12600.1 12605.9 7461.6 7636.9 7733.4 7740.0 7768.3 3200.9 3235.7 3243.7 3244.9 3279.8 785.3 945.5 959.9 968.3 991.1 29474.9 29492.1 29911.4 30572.8 30624.9 996.3 1009.0 1036.0 1037.1 1069.3 14536.4 15145.9 15351.4 15646.9 15980.6 164000.0 757875.0 769653.0 770801.0 957851.0 57629.0 57643.0 58130.7 58381.7 58412.7 1349.1 1371.5 1390.7 1391.2 1394.6 25751.6 30179.4 30740.4 30999.8 32937.5 55534.6 56336.0 56446.4 57109.0 57192.4 582554.0 592089.0 592573.0 593340.0 597330.0 42255.4 42323.0 42437.4 42601.4 42812.6 38930.8 46282.8 47020.8 47489.0 47655.3 39259.6 39277.8 39285.2 39956.4 40083.4 31833.5 38778.0 39079.6 39810.6 40483.8 38301.6 38684.6 39574.8 39777.8 41502.8 30768.5 30903.0 34420.1 35133.6 35207.0 25038.1 25380.0 25721.7 25815.1 26107.7 19:52:08 INFO - PROCESS | 1654 | 19:52:08 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:33961.14 Median:1660.43 stddev:95422.62 (5746.9%) stddev-sans-first:95656.89 19:52:08 INFO - PROCESS | 1654 | Values: 327.4 341.0 368.5 375.3 399.0 19536.4 20244.3 20701.9 21026.6 21162.9 16727.6 17236.9 20168.4 20773.8 20915.4 5576.9 5772.7 5819.7 5864.4 6062.6 5896.3 6060.6 6069.5 6073.3 6083.7 5703.9 5922.9 5958.8 6033.3 6034.8 5924.1 5957.6 5979.8 5987.6 6004.1 5038.4 5064.6 5200.3 5206.5 5213.9 24652.6 24658.9 25233.6 25577.6 25791.1 1944.9 2288.0 2330.3 2612.9 2993.4 10108.6 10176.7 12385.0 14794.0 15696.1 1872.6 1904.8 1906.7 1924.2 1983.1 119057.0 119888.4 127472.4 128042.7 128396.0 8976.9 10943.3 11440.8 11512.8 11583.3 14119.8 14142.4 14143.2 14146.4 14150.3 7060.4 8239.3 8514.7 8520.4 8563.4 8565.5 8576.7 8592.6 8610.6 8721.8 3907.3 4435.1 4437.9 4548.3 4572.1 1633.4 1802.9 1918.1 1951.6 1954.0 709.8 893.9 928.1 953.9 958.4 17961.8 19905.0 20724.3 20899.7 20941.2 899.0 928.6 936.0 954.7 987.3 8837.0 10501.7 11050.1 11053.2 11270.6 557959.0 561309.0 561754.0 569892.0 602812.0 89896.1 90769.7 116990.4 125155.0 130929.7 886.3 992.9 1016.3 1044.2 1048.5 18496.5 18820.9 18898.0 18963.3 19005.8 52966.7 55184.6 55457.7 55911.4 56564.4 79119.0 79733.0 80847.0 81171.2 82144.0 7720.8 7794.1 7921.8 7928.8 7976.9 10504.8 12097.3 12433.5 12436.4 12443.1 8264.7 10245.5 10580.4 10745.1 10766.3 10254.2 10270.2 10397.2 10466.6 10597.0 10646.4 10844.5 10938.0 10963.2 10972.8 7681.7 7807.5 7839.9 7918.7 7983.2 9149.3 9942.4 10097.5 10721.0 10827.5 19:52:08 INFO - PROCESS | 1654 | 19:52:08 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5673.69 Median:4855.40 stddev:3180.23 (65.5%) stddev-sans-first:3173.75 19:52:08 INFO - PROCESS | 1654 | Values: 1509.1 1544.1 1735.7 1767.3 1873.4 7965.4 8469.5 8562.4 9011.2 9048.5 8670.4 8786.8 8792.8 8820.5 8826.7 2071.3 2380.2 2385.3 2388.9 2397.6 2352.8 2369.9 2407.8 2409.1 2416.2 1975.5 2356.3 2374.3 2378.3 2384.6 2343.3 2345.3 2363.8 2367.7 2384.8 2350.0 2364.0 2366.2 2366.7 2368.0 8751.2 8803.3 8880.6 8889.9 8902.8 4194.9 4705.7 5236.1 5323.8 5329.8 3443.6 3564.1 3628.9 3643.0 3647.1 1048.5 1073.8 1088.1 1094.4 1099.3 9803.3 10091.5 10611.9 11661.4 11719.1 4534.7 4617.0 4823.4 4846.8 4869.8 9796.8 9854.7 9856.6 9873.9 9880.7 4656.9 5271.2 5291.7 5292.3 5312.0 4401.2 4720.6 4724.5 4864.0 4905.7 3379.5 3827.6 3923.8 3968.7 4019.4 1186.2 1247.2 1323.3 1336.3 1343.5 852.5 910.4 913.3 916.4 954.9 5706.9 5976.3 6009.2 6046.8 6047.9 439.5 460.3 460.8 466.2 468.7 3957.1 3965.9 4065.9 4076.9 4092.4 10589.7 10808.5 10902.6 10942.8 10952.8 10675.0 10705.1 10840.4 10892.0 10900.6 8094.9 8096.0 8129.5 8264.8 8307.8 5725.5 5833.1 5866.8 5895.5 5905.4 8434.5 10066.2 10155.7 10166.7 10182.6 9014.7 9329.7 10157.7 10387.7 10390.5 7130.4 7354.7 7383.2 7391.7 7581.4 8339.5 8442.8 8455.7 8473.9 8590.6 7362.7 7517.7 7586.8 7606.6 7624.6 7273.3 7328.4 7423.1 7432.1 7470.2 6986.8 7007.0 7052.1 7080.5 7106.9 4961.1 5002.6 5052.8 5075.1 5128.1 5011.5 5033.4 5053.0 5079.3 5224.4 19:52:08 INFO - PROCESS | 1654 | 19:52:08 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4485.01 Median:4857.49 stddev:1383.60 (28.5%) stddev-sans-first:1351.27 19:52:08 INFO - PROCESS | 1654 | Values: 295.6 305.0 311.7 315.6 318.0 5240.7 5517.3 5605.9 5617.3 5677.6 4485.3 4822.5 5706.6 5710.8 5726.9 3501.7 4315.4 4331.2 4338.6 4434.3 4145.8 4258.6 4306.0 4325.6 4344.6 4222.8 4228.6 4235.3 4258.9 4259.0 4246.7 4252.3 4257.7 4267.5 4268.6 2873.6 2986.7 3432.1 3602.3 3682.9 4991.6 5405.4 5491.5 5565.4 5575.4 4401.4 4584.4 4830.5 4855.5 4861.2 5441.5 5718.7 5897.8 6010.5 6019.1 4006.7 4006.9 4017.0 4019.3 4022.9 5679.2 5840.0 6332.8 6334.1 6394.7 3914.3 3997.7 4011.9 4023.0 4046.2 4063.4 4103.0 4113.3 4124.4 4140.3 4837.8 4883.7 4886.4 4895.0 4895.5 5905.1 6074.7 6088.2 6090.8 6142.5 1180.5 1274.9 1338.3 1359.6 1388.0 892.6 893.9 930.2 939.9 942.6 4339.8 5449.5 5599.0 5604.0 5610.5 5448.1 5620.8 6104.9 6131.6 6185.2 5244.5 5378.5 5526.3 5595.7 5597.8 2200.8 2373.6 2495.0 2560.3 2755.3 4492.2 4810.9 5044.7 5411.0 5445.4 4489.0 5156.3 5256.4 5305.0 5337.7 4510.9 4734.9 4784.0 4834.4 4887.3 4347.7 4395.9 4829.3 4877.9 5103.9 4021.2 4268.7 4351.9 4392.4 5044.9 5279.3 5358.0 5363.6 5391.1 5417.6 3210.9 3250.7 3485.9 3487.4 3535.3 4836.3 4949.6 4982.8 5115.4 5223.0 4531.8 4580.5 4957.5 4983.8 5053.0 4077.6 4325.8 4577.4 4758.8 4815.9 4563.3 4859.5 4886.9 4921.5 5912.2 5253.1 5780.4 6029.6 6072.8 6141.6 4937.0 5302.9 5712.4 5768.7 5939.8 19:52:08 INFO - PROCESS | 1654 | 19:52:08 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:25718.13 Median:3792.32 stddev:41686.55 (1099.2%) stddev-sans-first:41761.24 19:52:08 INFO - PROCESS | 1654 | Values: 720.2 723.3 759.2 765.4 806.2 15026.3 15726.4 16068.3 16084.0 16140.8 14326.4 14490.1 14738.3 14742.6 14777.8 861.5 880.3 881.3 885.8 886.3 676.8 700.1 875.5 887.0 888.0 919.0 930.1 933.1 935.8 937.5 913.3 926.1 927.2 932.5 937.7 666.0 691.9 696.4 697.2 698.2 14717.9 14728.6 14739.3 14760.9 14772.0 14316.2 14333.9 14413.9 14446.8 14781.8 11086.5 11293.7 11394.7 11653.8 11745.4 3802.9 3819.3 3831.6 3848.6 3943.7 92026.5 96620.5 96843.0 119910.4 120354.4 9278.4 10168.7 10654.9 10903.3 11030.8 20847.9 21105.3 21134.6 21137.4 21192.3 7812.6 7881.9 7894.5 7895.1 8211.5 4585.4 4617.0 4627.6 4676.3 4784.7 3618.8 3701.5 3769.3 3776.8 3781.7 620.4 682.3 690.2 706.1 706.6 519.4 526.1 606.6 607.9 663.5 9457.5 9461.5 9479.0 9594.1 9696.7 641.7 672.6 674.1 688.4 703.3 3748.9 4441.8 4586.9 4602.6 4709.8 152749.9 155119.0 157778.0 161305.0 164077.2 119035.4 135765.0 138212.6 141911.0 143270.3 119526.0 121086.4 121795.4 122310.0 122492.4 8871.5 8899.9 8983.5 9024.3 9031.6 66708.7 66940.0 68854.0 69182.0 69412.2 85384.0 88532.0 94594.5 96818.0 102936.0 12975.9 13015.7 13120.5 13230.3 13858.8 13476.3 13698.0 14592.4 15085.9 15086.6 13497.7 13574.8 13855.4 13934.7 14150.5 13812.9 13842.4 13871.9 14017.9 14138.4 13314.5 13629.7 13795.2 13814.7 13901.9 9191.4 11263.1 11269.4 11384.9 11494.5 9834.5 10556.3 11341.6 11513.4 11694.4 19:52:08 INFO - PROCESS | 1654 | -------- Summary: end -------- 19:52:08 INFO - PROCESS | 1654 | 19:52:09 INFO - TEST-INFO | 1654: exit 0 19:52:09 INFO - TEST-OK | dromaeo_css | took 1113608ms 19:52:09 INFO - TEST-START | kraken 19:52:09 INFO - operating with platform_type : mac_ 19:52:09 INFO - Initialising browser for kraken test... 19:52:09 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/tmpflVMnP/profile http://localhost:49267/getInfo.html) 19:52:11 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 19:52:11 INFO - PROCESS | 1662 | colorDepth:24 19:52:11 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 19:52:11 INFO - PROCESS | 1662 | __metrics 19:52:12 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 19:52:12 INFO - TEST-INFO | 1662: exit 0 19:52:12 INFO - Browser initialized. 19:52:12 INFO - Running cycle 1/1 for kraken test... 19:52:12 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/T/tmpflVMnP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:52:24 INFO - PROCESS | 1664 | RSS: Main: 245121024 19:52:24 INFO - PROCESS | 1664 | 19:52:27 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 19:52:28 INFO - PROCESS | 1664 | RSS: Main: 257810432 19:52:28 INFO - PROCESS | 1664 | 19:52:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 19:52:31 INFO - PROCESS | 1664 | RSS: Main: 266461184 19:52:31 INFO - PROCESS | 1664 | 19:52:35 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 19:52:36 INFO - PROCESS | 1664 | RSS: Main: 264187904 19:52:36 INFO - PROCESS | 1664 | 19:52:38 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 19:52:38 INFO - PROCESS | 1664 | RSS: Main: 266514432 19:52:38 INFO - PROCESS | 1664 | 19:52:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:52:41 INFO - PROCESS | 1664 | RSS: Main: 266919936 19:52:41 INFO - PROCESS | 1664 | 19:52:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 19:52:45 INFO - PROCESS | 1664 | RSS: Main: 267747328 19:52:45 INFO - PROCESS | 1664 | 19:52:48 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 19:52:48 INFO - PROCESS | 1664 | RSS: Main: 268013568 19:52:48 INFO - PROCESS | 1664 | 19:52:51 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 19:52:52 INFO - PROCESS | 1664 | RSS: Main: 268345344 19:52:52 INFO - PROCESS | 1664 | 19:52:53 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:52:54 INFO - PROCESS | 1664 | RSS: Main: 267866112 19:52:54 INFO - PROCESS | 1664 | 19:52:56 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:52:57 INFO - PROCESS | 1664 | RSS: Main: 267526144 19:52:57 INFO - PROCESS | 1664 | 19:52:59 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:53:00 INFO - PROCESS | 1664 | RSS: Main: 267821056 19:53:00 INFO - PROCESS | 1664 | 19:53:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:53:03 INFO - PROCESS | 1664 | RSS: Main: 268107776 19:53:03 INFO - PROCESS | 1664 | 19:53:06 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:53:07 INFO - PROCESS | 1664 | RSS: Main: 268185600 19:53:07 INFO - PROCESS | 1664 | 19:53:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 19:53:09 INFO - PROCESS | 1664 | RSS: Main: 270241792 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - PROCESS | 1664 | __start_tp_report 19:53:09 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 19:53:09 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 19:53:09 INFO - PROCESS | 1664 | |i|pagename|runs| 19:53:09 INFO - PROCESS | 1664 | |0;ai-astar;107;117;118;115;115;116;122;118;117;131 19:53:09 INFO - PROCESS | 1664 | |1;audio-beat-detection;144;110;132;156;112;165;162;111;156;110 19:53:09 INFO - PROCESS | 1664 | |2;audio-dft;190;209;216;257;185;269;177;242;204;178 19:53:09 INFO - PROCESS | 1664 | |3;audio-fft;67;116;108;181;54;79;49;65;144;89 19:53:09 INFO - PROCESS | 1664 | |4;audio-oscillator;69;73;121;80;97;117;95;91;66;110 19:53:09 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;88;87;88;86;79;130;136;86;105;86 19:53:09 INFO - PROCESS | 1664 | |6;imaging-darkroom;117;148;102;111;150;121;95;95;95;95 19:53:09 INFO - PROCESS | 1664 | |7;imaging-desaturate;100;93;87;93;118;121;90;91;90;93 19:53:09 INFO - PROCESS | 1664 | |8;json-parse-financial;53;57;58;59;46;51;69;89;53;46 19:53:09 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;66;69;67;72;71;83;97;70;71;74 19:53:09 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;112;110;103;89;88;82;91;87;94;86 19:53:09 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;137;135;142;141;140;149;140;137;173;149 19:53:09 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;170;171;180;166;207;156;235;239;172;177 19:53:09 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;66;69;69;71;70;68;69;67;69;70 19:53:09 INFO - PROCESS | 1664 | __end_tp_report 19:53:09 INFO - PROCESS | 1664 | __start_cc_report 19:53:09 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,951 19:53:09 INFO - PROCESS | 1664 | __end_cc_report 19:53:09 INFO - PROCESS | 1664 | __startTimestamp1472698389042__endTimestamp 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - PROCESS | 1664 | ------- Summary: start ------- 19:53:09 INFO - PROCESS | 1664 | Number of tests: 14 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:117.60 Median:117.50 stddev:6.04 (5.1%) stddev-sans-first:5.04 19:53:09 INFO - PROCESS | 1664 | Values: 107.0 117.0 118.0 115.0 115.0 116.0 122.0 118.0 117.0 131.0 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:135.80 Median:150.00 stddev:23.45 (15.6%) stddev-sans-first:24.69 19:53:09 INFO - PROCESS | 1664 | Values: 144.0 110.0 132.0 156.0 112.0 165.0 162.0 111.0 156.0 110.0 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:212.70 Median:212.50 stddev:33.09 (15.6%) stddev-sans-first:34.06 19:53:09 INFO - PROCESS | 1664 | Values: 190.0 209.0 216.0 257.0 185.0 269.0 177.0 242.0 204.0 178.0 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:95.20 Median:59.50 stddev:42.40 (71.3%) stddev-sans-first:43.73 19:53:09 INFO - PROCESS | 1664 | Values: 67.0 116.0 108.0 181.0 54.0 79.0 49.0 65.0 144.0 89.0 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:76.50 stddev:19.82 (25.9%) stddev-sans-first:19.21 19:53:09 INFO - PROCESS | 1664 | Values: 69.0 73.0 121.0 80.0 97.0 117.0 95.0 91.0 66.0 110.0 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:97.10 Median:86.00 stddev:20.06 (23.3%) stddev-sans-first:21.01 19:53:09 INFO - PROCESS | 1664 | Values: 88.0 87.0 88.0 86.0 79.0 130.0 136.0 86.0 105.0 86.0 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:112.90 Median:122.50 stddev:21.33 (17.4%) stddev-sans-first:22.57 19:53:09 INFO - PROCESS | 1664 | Values: 117.0 148.0 102.0 111.0 150.0 121.0 95.0 95.0 95.0 95.0 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:97.60 Median:90.50 stddev:12.04 (13.3%) stddev-sans-first:12.74 19:53:09 INFO - PROCESS | 1664 | Values: 100.0 93.0 87.0 93.0 118.0 121.0 90.0 91.0 90.0 93.0 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:58.10 Median:57.50 stddev:12.78 (22.2%) stddev-sans-first:13.43 19:53:09 INFO - PROCESS | 1664 | Values: 53.0 57.0 58.0 59.0 46.0 51.0 69.0 89.0 53.0 46.0 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:74.00 Median:71.50 stddev:9.35 (13.1%) stddev-sans-first:9.45 19:53:09 INFO - PROCESS | 1664 | Values: 66.0 69.0 67.0 72.0 71.0 83.0 97.0 70.0 71.0 74.0 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:94.20 Median:87.50 stddev:10.48 (12.0%) stddev-sans-first:8.91 19:53:09 INFO - PROCESS | 1664 | Values: 112.0 110.0 103.0 89.0 88.0 82.0 91.0 87.0 94.0 86.0 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:144.30 Median:141.50 stddev:11.13 (7.9%) stddev-sans-first:11.48 19:53:09 INFO - PROCESS | 1664 | Values: 137.0 135.0 142.0 141.0 140.0 149.0 140.0 137.0 173.0 149.0 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.30 Median:178.50 stddev:29.30 (16.4%) stddev-sans-first:30.40 19:53:09 INFO - PROCESS | 1664 | Values: 170.0 171.0 180.0 166.0 207.0 156.0 235.0 239.0 172.0 177.0 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.00 stddev:1.48 (2.1%) stddev-sans-first:1.17 19:53:09 INFO - PROCESS | 1664 | Values: 66.0 69.0 69.0 71.0 70.0 68.0 69.0 67.0 69.0 70.0 19:53:09 INFO - PROCESS | 1664 | -------- Summary: end -------- 19:53:09 INFO - PROCESS | 1664 | 19:53:09 INFO - TEST-INFO | 1664: exit 0 19:53:09 INFO - TEST-OK | kraken | took 60543ms 19:53:09 INFO - SUITE-END | took 1174s 19:53:10 INFO - Completed test suite (00:19:34) 19:53:10 INFO - javascript benchmark 19:53:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7253.624780234507, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3367.8147701876396, 3398.49022646603, 3411.4541673332274, 3415.992282276728, 3437.2953544523375, 4726.527347265272, 5267.709411194258, 5654.067078931971, 5800.622959507629, 5830.475257226849, 5781.13283007549, 5835.474807267345, 5857.3849745776715, 5892.14563888236, 5893.263342082239, 5202.089634313989, 5250.8687282817855, 5262.81580144695, 5344.118220493614, 5367.9800837840885, 5321.255024296583, 5337.172738225562, 5371.140617501201, 5380.24676545284, 5403.0, 5066.898662026757, 5153.381594208681, 5215.6609820361555, 5280.3663560372615, 5310.893782124336, 4556.54434556545, 5038.823641172578, 5151.304573882516, 5180.792768289264, 5204.583633309327, 5265.131253343194, 5293.602979776532, 5294.973525132388, 5302.522772950414, 5320.255164277004, 5619.438056194348, 5706.201131841545, 5786.652800831921, 5828.446297601721, 5834.474897259267, 6163.0, 6232.532560058014, 6232.563720539564, 6251.0, 6304.7793327233785, 6129.328227221994, 6194.782868674771, 6229.408206220402, 6242.719077641517, 6256.468200202989, 5854.736536855852, 5928.229330187048, 5944.197533333031, 5962.672053037125, 6029.939700602938, 6241.095041218998, 6266.780662676784, 6273.247210334698, 6274.027525733519, 6277.874442511215, 4752.714837109785, 4918.409790825124, 5020.698758074527, 6080.422359875804, 6209.9068513972325, 5073.365829271341, 6187.659678717714, 6234.0337247726675, 6269.996800511896, 6272.184615999982, 5503.229547863302, 5967.880642387128, 6186.072089186568, 6212.9378706213265, 6264.0, 4961.8511444656715, 5466.398696143421, 6153.630782153085, 6192.628442293477, 6214.347493513206, 4221.852235171754, 4323.156984388014, 4969.378827646544, 4990.226514890198, 5028.270900719448, 3872.6901847852105, 4313.439252897103, 4449.1101779644205, 4457.576530229623, 4488.102379524043, 5723.284589426322, 5822.010258256107, 5831.096180092092, 5833.708314584339, 5871.266091738533, 6134.222293197073, 6152.692365381713, 6155.84610384735, 6162.0, 6178.042403427476, 5835.183074369592, 5851.795187168514, 5863.208466857005, 5871.706414679249, 5897.439743224472, 5378.031954248195, 5514.6691198528215, 5520.530754885799, 5533.584981126432, 5537.086380747212, 6227.657478838707, 6250.1874756281395, 6296.653684047421, 6376.139221205078, 6389.680515974275, 6203.286622038523, 6211.2857021441305, 6220.502359811295, 6241.937580624136, 6254.9687251563455, 6208.068789681584, 6367.490600751836, 6430.7749228776765, 6435.678216089271, 6437.0666253392055, 6138.572327483795, 6141.762184092961, 6149.0, 6220.595661282002, 6232.844178895382, 6209.099680546386, 6215.440610345091, 6220.626762394271, 6221.564490485623, 6225.190725205875, 5726.341470730753, 6074.969625151846, 6235.251769787655, 6237.033259844731, 6262.46769024638, 6060.454559089703, 6111.310664112268, 6121.938780612137, 6143.72353244114, 6163.260408750979, 5865.853353666022, 6003.90994135097, 6046.939530604814, 6053.213082299273, 6091.086337049478, 5432.0, 6087.512998960161, 6180.258368995749, 6194.0, 6211.254649442096, 6020.036794113096, 6024.3975602439405, 6064.06007068905, 6064.818055458343, 6143.692815359124], "value": 5671.136153895074, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.18392669085665, 471.2601216090738, 491.4986713552177, 509.54270785553337, 556.379636705074, 29880.999999999975, 40225.59774402259, 40263.79208623739, 40340.0, 40563.39154912675, 40981.180376392454, 41396.58603413965, 41454.37818432726, 41460.0, 41547.58452415482, 9569.3779904306, 9751.97604251554, 9760.267979901535, 9773.657921972732, 9789.804203915917, 9694.17599504043, 9802.950985245065, 9808.411495310269, 9817.901820981806, 9819.852702209473, 9773.267004974656, 9800.705978820612, 9820.11618954294, 9824.0, 9866.506674666274, 9773.0, 9782.266330025277, 9782.413055216639, 9789.31474796761, 9810.96984816591, 7472.850542989164, 9090.727278181665, 9669.70990870275, 9756.365836220693, 9768.90231097687, 39412.605873941466, 39737.40393894094, 39882.002949926195, 40016.59983400187, 40129.19741605181, 3456.412409890325, 3481.54739883816, 3532.275883443888, 3904.921901561953, 3969.8809035728973, 30470.390592188254, 31150.0, 31302.373952520822, 31418.37163256722, 31581.8420907894, 3257.7393808495262, 3285.0637568292923, 3289.0297362278184, 3352.3127758809387, 3402.9829850850833, 468000.65999670123, 477291.0, 480962.0, 481818.0, 482353.0, 32875.1781205467, 49020.03919843167, 53981.46018539764, 56279.718601406734, 56401.43598564044, 33787.66212337894, 34358.656413436045, 34704.0, 34735.82632086874, 34902.30195396129, 14526.418943242363, 14562.199079050753, 14668.853311466748, 14693.265336733335, 14717.779233311509, 12378.133530652767, 12539.874601254052, 12571.120021598583, 12600.11799174049, 12605.865472107555, 7461.552306861606, 7636.885446718304, 7733.381329493627, 7739.993800805972, 7768.2620151086685, 3200.919977000547, 3235.7411407087375, 3243.659415761358, 3244.918877028046, 3279.7704160709, 785.3206975965749, 945.4516380499294, 959.8608201810698, 968.2641192693603, 991.0832479956011, 29474.852625736734, 29492.115236542937, 29911.40177196444, 30572.84713576418, 30624.92812751543, 996.2926322311096, 1009.0464511037146, 1035.9689209323733, 1037.097724979273, 1069.3476979042778, 14536.418543258364, 15145.924270378577, 15351.385944562093, 15646.9217653911, 15980.600484987968, 164000.0, 757875.0, 769653.0, 770801.0, 957851.0, 57629.0, 57643.0, 58130.70934645384, 58381.70809146012, 58412.70793646004, 1349.0961056092442, 1371.4994027180207, 1390.6870954035262, 1391.2139641102744, 1394.6234516680236, 25751.6137257945, 30179.396412072074, 30740.385192296475, 30999.760009599362, 32937.50593741048, 55534.61163470784, 56336.0, 56446.43553564576, 57109.0, 57192.42807571871, 582554.0, 592089.0, 592573.0, 593340.0, 597330.0, 42255.366169506866, 42323.0, 42437.36343954905, 42601.36097958471, 42812.57187428086, 38930.80534597309, 46282.76858615685, 47020.7648961753, 47489.0, 47655.28517072177, 39259.60740392674, 39277.80361098176, 39285.2142957145, 39956.40065399079, 40083.398749018204, 31833.522497163023, 38778.0, 39079.60920390873, 39810.601893981846, 40483.79758101191, 38301.61698382981, 38684.6131538681, 39574.80212599034, 39777.80111099426, 41502.792486037375, 30768.538471922493, 30903.0, 34420.13949651179, 35133.648663513035, 35207.0, 25038.116380359177, 25380.0, 25721.742782571935, 25815.096471623405, 26107.73892261129], "value": 18897.110258942364, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.4482496992569, 340.97954122752634, 368.4767629965449, 375.2888276715625, 398.962098600633, 19536.41390758276, 20244.291449799242, 20701.896490517527, 21026.579468410622, 21162.89418552905, 16727.581810454714, 17236.913815430937, 20168.39494815154, 20773.79226207741, 20915.37253882382, 5576.944230557703, 5772.711364431782, 5819.67991760453, 5864.4135586441225, 6062.605930614518, 5896.292444906596, 6060.636361818283, 6069.544784141185, 6073.301570319403, 6083.695815209222, 5703.8574035649235, 5922.851928701773, 5958.821235362924, 6033.27600687918, 6034.758609655631, 5924.0521516557355, 5957.642541447527, 5979.820605381846, 5987.64074155552, 6004.099385092228, 5038.370203724535, 5064.645474816764, 5200.349956255468, 5206.505381988705, 5213.921791173136, 24652.630210546857, 24658.876705616356, 25233.62149567758, 25577.59323237221, 25791.097311594003, 1944.9027548622512, 2288.039023610088, 2330.265966220652, 2612.9477410451686, 2993.4162838246552, 10108.595656173744, 10176.694699159038, 12385.0, 14794.0, 15696.13671248081, 1872.616113696689, 1904.8190421909896, 1906.675865102922, 1924.191839427444, 1983.0580474274748, 119057.0, 119888.40055799752, 127472.36263818714, 128042.71957280401, 128396.0, 8976.8653470198, 10943.343399396063, 11440.828387574193, 11512.769744605228, 11583.305001699924, 14119.78820317696, 14142.36359363831, 14143.222122783345, 14146.434142634385, 14150.29248537569, 7060.364567188978, 8239.258466737903, 8514.70198543048, 8520.446170998865, 8563.443376180652, 8565.52889591066, 8576.699815506427, 8592.613332400057, 8610.612522436506, 8721.781955451164, 3907.257621051991, 4435.090806384684, 4437.889052773707, 4548.2722764358205, 4572.08558288337, 1633.444628826181, 1802.9008404537876, 1918.0723373010035, 1951.6096780643927, 1953.98046019538, 709.7862654860144, 893.8525143351275, 928.1368327455531, 953.8791919494566, 958.4441024205944, 17961.820381796275, 19905.0, 20724.274650387164, 20899.68650470244, 20941.16823994438, 898.9910100899039, 928.5635751196927, 936.0, 954.6992697300328, 987.3434166279344, 8837.0, 10501.737456563647, 11050.115990720724, 11053.17101217412, 11270.605528806453, 557959.0, 561309.0, 561754.0, 569892.0, 602812.0, 89896.10103898877, 90769.73075673162, 116990.41504792421, 125155.0, 130929.69070309175, 886.3219636977717, 992.93545919515, 1016.2987538598492, 1044.2064031336088, 1048.491481631408, 18496.537586560444, 18820.905895470434, 18898.0, 18963.33628323002, 19005.809941900403, 52966.735166323924, 55184.620384489106, 55457.72271138619, 55911.44088559225, 56564.43435565592, 79119.0, 79733.0, 80847.0, 81171.18828811636, 82144.0, 7720.806979825325, 7794.142644309008, 7921.762347129596, 7928.801779955547, 7976.9202307976175, 10504.842427363748, 12097.334646594521, 12433.502659893504, 12436.440360183633, 12443.128980971245, 8264.710735124483, 10245.487725613839, 10580.36517808934, 10745.086667633026, 10766.300190487593, 10254.230932680228, 10270.178385728974, 10397.220208484545, 10466.581336746749, 10597.0, 10646.414447205478, 10844.511996960313, 10937.960893715084, 10963.232573719763, 10972.835407468734, 7681.692732290646, 7807.531548107132, 7839.882401764092, 7918.722844700408, 7983.2415920487465, 9149.31380146505, 9942.403455792675, 10097.5456104474, 10721.03510684011, 10827.458627068772], "value": 9428.590558198672, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1509.1020842598653, 1544.1275679241228, 1735.72228443449, 1767.2842498787995, 1873.3630565607707, 7965.44241903067, 8469.534175620338, 8562.357823163273, 9011.188992990637, 9048.547572621368, 8670.43642163259, 8786.780330491742, 8792.78018049549, 8820.514871682071, 8826.691065812698, 2071.275053731193, 2380.1907351500486, 2385.3321070100315, 2388.904443822245, 2397.592409290416, 2352.8235382346306, 2369.869657168855, 2407.7953373963232, 2409.084547871806, 2416.214730212679, 1975.4666240115232, 2356.2813341930705, 2374.3114496795906, 2378.3459548624096, 2384.6065399209197, 2343.3438637181616, 2345.308134100436, 2363.7872591466767, 2367.6922000140044, 2384.7972922301624, 2350.0247397330086, 2363.9527209455714, 2366.2309749331534, 2366.6804981327473, 2367.988160059189, 8751.212390884788, 8803.295736341077, 8880.579107342859, 8889.86665200016, 8902.777430564223, 4194.916101677949, 4705.670603057787, 5236.109861323546, 5323.760430780624, 5329.786808527654, 3443.6039855416448, 3564.0733409313507, 3628.9455658165148, 3643.0, 3647.0882279430143, 1048.5176818663347, 1073.7637719701731, 1088.0860077534908, 1094.370736826328, 1099.3458891959351, 9803.31376803624, 10091.49542522871, 10611.893881061167, 11661.416929153509, 11719.062475001894, 4534.727916325031, 4617.007343421181, 4823.445303790109, 4846.80612775485, 4869.780859861315, 9796.804063918824, 9854.704358869245, 9856.5564549594, 9873.851892221623, 9880.703578892644, 4656.905627177611, 5271.1829666401, 5291.682499050069, 5292.338457692788, 5312.0, 4401.207782599163, 4720.598749106296, 4724.527547245317, 4864.0028794097125, 4905.730184839868, 3379.5268662387284, 3827.598102199229, 3923.7645741255615, 3968.722189446711, 4019.376996565537, 1186.2230239193343, 1247.1706315300405, 1323.2722002898367, 1336.2984433172662, 1343.543195313598, 852.4799872078028, 910.4127837544746, 913.255696607263, 916.4180745226829, 954.8758661373977, 5706.857328566819, 5976.282846058414, 6009.188759517497, 6046.848828779228, 6047.879042419127, 439.49019137799996, 460.26587592789446, 460.78112896373955, 466.20279322359147, 468.70705808869457, 3957.109650328653, 3965.8810235692977, 4065.89835254121, 4076.8776936691947, 4092.4066010428323, 10589.682309530726, 10808.459577021275, 10902.563897444013, 10942.835857462145, 10952.835707464394, 10675.0, 10705.1435885129, 10840.403777792297, 10892.0, 10900.618478353219, 8094.919050809416, 8096.02797008307, 8129.471584347117, 8264.793380165544, 8307.750767476864, 5725.51333136688, 5833.1250312451775, 5866.765329386777, 5895.469407753323, 5905.379935106924, 8434.451760635538, 10066.245031622804, 10155.66419723453, 10166.694999149742, 10182.592696292066, 9014.684486042957, 9329.740485034434, 10157.695269141643, 10387.68836934893, 10390.480475976323, 7130.358267755927, 7354.705811767683, 7383.224761399975, 7391.741289054858, 7581.393488521016, 8339.54132522717, 8442.788930276793, 8455.74632761018, 8473.91526084756, 8590.613422395882, 7362.678424631856, 7517.699292028257, 7586.848263034598, 7606.619669016638, 7624.58064806441, 7273.3453989141235, 7328.413726901728, 7423.072115985502, 7432.108147022392, 7470.178280389261, 6986.790396288119, 7007.0, 7052.0832291801735, 7080.468964827762, 7106.893396599158, 4961.107000739901, 5002.599792016703, 5052.823151189691, 5075.11185542536, 5128.102582048052, 5011.498850114959, 5033.421156567042, 5052.9747351263, 5079.314292570456, 5224.399194092728], "value": 4496.049320572487, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.59651076280875, 304.9878004879805, 311.71010959807376, 315.60706919884734, 318.03793524588133, 5240.711760853152, 5517.2551705519745, 5605.887882242358, 5617.297837770278, 5677.57418193635, 4485.349624304474, 4822.529128615771, 5706.600537962344, 5710.800121995731, 5726.942730572693, 3501.7373696972827, 4315.417418648473, 4331.180904020319, 4338.594203550214, 4434.312681534368, 4145.79271036447, 4258.616724494795, 4305.978470107645, 4325.589069038437, 4344.608985191332, 4222.767747773872, 4228.640565551932, 4235.301175306071, 4258.9148217035645, 4259.019555906503, 4246.681498887596, 4252.319628859368, 4257.6806739494505, 4267.466566679165, 4268.637165840907, 2873.6264285642733, 2986.686397928208, 3432.0608855996534, 3602.261536385061, 3682.9263414731554, 4991.627302491844, 5405.351357837046, 5491.450854914477, 5565.443455654402, 5575.414581468968, 4401.427814384142, 4584.381108550369, 4830.492798256168, 4855.514448555116, 4861.197902346109, 5441.5102640762525, 5718.71406429681, 5897.849919265745, 6010.549208809357, 6019.0738963531685, 4006.6994975376965, 4006.9146756996947, 4017.0359113812765, 4019.336809426441, 4022.8591999279884, 5679.211048519703, 5840.036393994986, 6332.810015699536, 6334.081558174085, 6394.712237949257, 3914.256291304643, 3997.6601988831276, 4011.8796436106963, 4023.0, 4046.2312160689376, 4063.3904914262507, 4102.9895351277655, 4113.321301985199, 4124.360724087771, 4140.3375459926265, 4837.830675926396, 4883.682560633548, 4886.364772579542, 4895.04546613407, 4895.485973972753, 5905.114232865105, 6074.717324286367, 6088.178095956989, 6090.756369745161, 6142.508599312045, 1180.5218886351042, 1274.9099519910528, 1338.297393868227, 1359.6335682638885, 1388.02838013389, 892.6340200517757, 893.9418937769025, 930.1675000874179, 939.8872135343665, 942.6182396129578, 4339.784860239075, 5449.509544141047, 5599.0, 5604.047311956977, 5610.49505544503, 5448.073827449343, 5620.775168993195, 6104.938950610526, 6131.570790044743, 6185.195924529782, 5244.5279924806955, 5378.542823859937, 5526.309211348582, 5595.71298601324, 5597.80407685729, 2200.8019278265033, 2373.5964885969083, 2494.975050249511, 2560.2831207261997, 2755.3249453883527, 4492.236319825637, 4810.855674329776, 5044.747762611854, 5411.0, 5445.401005889349, 4489.0, 5156.252343410185, 5256.421793602701, 5305.0, 5337.733113344395, 4510.902679971611, 4734.928976065362, 4783.971446139097, 4834.419869615598, 4887.31197841193, 4347.673924455742, 4395.901024743814, 4829.27560865873, 4877.926955965756, 5103.87240318995, 4021.155557332993, 4268.740721487106, 4351.912961740811, 4392.386530014636, 5044.949550504448, 5279.310620601371, 5358.0, 5363.624546281723, 5391.137418013099, 5417.647852889707, 3210.855511502035, 3250.724492855058, 3485.89542313731, 3487.4245749451056, 3535.2929414116766, 4836.347093142381, 4949.564626258345, 4982.800687972585, 5115.386153661584, 5223.0, 4531.773411329486, 4580.519045500175, 4957.454679985271, 4983.75081245929, 5052.9747351263, 4077.5922407758985, 4325.782362196958, 4577.382053422745, 4758.8096476141955, 4815.927761083657, 4563.269876819693, 4859.465458799458, 4886.87601851569, 4921.507849215193, 5912.201852749996, 5253.080710875505, 5780.364159942319, 6029.577929545065, 6072.817815465543, 6141.600795948428, 4937.0, 5302.893942121059, 5712.428757124254, 5768.682722450306, 5939.7921072763975], "value": 4047.744837814498, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.2473415281031, 723.2622724820682, 759.1914610939349, 765.3800421658457, 806.1696452653765, 15026.323815428304, 15726.449574264903, 16068.27692753826, 16084.0, 16140.757888631651, 14326.355314010869, 14490.058146220488, 14738.263086845669, 14742.557723268292, 14777.778333325008, 861.5003298087125, 880.3485420788602, 881.3478026260585, 885.8272636835819, 886.2555453419127, 676.7935779587224, 700.0968750312092, 875.4528419737663, 887.0, 888.0231745080424, 918.9770255743626, 930.0606387548557, 933.0762545080389, 935.7707361696393, 937.4609599480292, 913.2556966072663, 926.1340646495548, 927.1609193679697, 932.4684929590136, 937.7093101138635, 665.9567128136656, 691.8616276744672, 696.4428457234243, 697.166885571742, 698.2075344483997, 14717.926410367987, 14728.55814325572, 14739.336729847182, 14760.926195369062, 14772.0, 14316.21260830654, 14333.928330358385, 14413.855861441461, 14446.783298250535, 14781.778273325908, 11086.501107450184, 11293.717657058558, 11394.658160255205, 11653.766924661544, 11745.354005529693, 3802.9429558556644, 3819.2552452271816, 3831.63599458051, 3848.5766565677754, 3943.684505239574, 92026.5398673009, 96620.51689741577, 96843.0, 119910.40044799808, 120354.3982280083, 9278.443293402417, 10168.745781355525, 10654.89345106539, 10903.29128606638, 11030.834537481944, 20847.895760521405, 21105.26131585387, 21134.577308453743, 21137.365879023346, 21192.258270960443, 7812.570039695468, 7881.921180788233, 7894.526328420313, 7895.092064412551, 8211.507309561446, 4585.426821647294, 4616.976915115471, 4627.583517483443, 4676.3453116563705, 4784.712917224978, 3618.819059047037, 3701.463287823304, 3769.3403654360377, 3776.773393596393, 3781.697464202857, 620.4385031546458, 682.3006418421058, 690.1545606631936, 706.060938951204, 706.6466766616692, 519.4364114935262, 526.1345087331395, 606.5753972219464, 607.875385545962, 663.4659099424913, 9457.479838608942, 9461.488201818476, 9478.952605237067, 9594.088561586637, 9696.660616878517, 641.682367228225, 672.5964421347251, 674.1404708995972, 688.4458011300951, 703.3002162847897, 3748.850045998184, 4441.844535441309, 4586.908261834745, 4602.619214235716, 4709.835155769531, 152749.8900044008, 155119.0, 157778.0, 161305.0, 164077.17961410116, 119035.40482297706, 135765.0, 138212.61787381998, 141911.0, 143270.2836485811, 119526.0, 121086.39456802837, 121795.39102304431, 122310.0, 122492.38753806351, 8871.467711937305, 8899.866502002606, 8983.46099234048, 9024.300405172587, 9031.593578288861, 66708.6664566674, 66940.0, 68853.96719049118, 69182.0, 69412.223511059, 85384.0, 88532.0, 94594.52702736443, 96818.0, 102936.0, 12975.870241297465, 13015.739685206054, 13120.475180993035, 13230.338483075615, 13858.792118118035, 13476.326183690971, 13698.0, 14592.416303348173, 15085.924570377078, 15086.622834429227, 13497.693337963427, 13574.796378054534, 13855.445782168601, 13934.721305573628, 14150.504732334319, 13812.861871381157, 13842.377093030636, 13871.861281387057, 14017.948653850797, 14138.363773630006, 13314.46742130304, 13629.727405452033, 13795.172289662652, 13814.65463363424, 13901.852918395754, 9191.402558833654, 11263.078813690068, 11269.436528173723, 11384.943075284571, 11494.54021839117, 9834.510172081275, 10556.313839600401, 11341.583812838557, 11513.366764828015, 11694.356810375511], "value": 7920.069263454982, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1587.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 118.0, 115.0, 115.0, 116.0, 122.0, 118.0, 117.0, 131.0], "value": 117.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 110.0, 132.0, 156.0, 112.0, 165.0, 162.0, 111.0, 156.0, 110.0], "value": 135.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 209.0, 216.0, 257.0, 185.0, 269.0, 177.0, 242.0, 204.0, 178.0], "value": 212.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 116.0, 108.0, 181.0, 54.0, 79.0, 49.0, 65.0, 144.0, 89.0], "value": 95.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 121.0, 80.0, 97.0, 117.0, 95.0, 91.0, 66.0, 110.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 86.0, 79.0, 130.0, 136.0, 86.0, 105.0, 86.0], "value": 97.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 148.0, 102.0, 111.0, 150.0, 121.0, 95.0, 95.0, 95.0, 95.0], "value": 112.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 93.0, 87.0, 93.0, 118.0, 121.0, 90.0, 91.0, 90.0, 93.0], "value": 97.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 57.0, 58.0, 59.0, 46.0, 51.0, 69.0, 89.0, 53.0, 46.0], "value": 58.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 67.0, 72.0, 71.0, 83.0, 97.0, 70.0, 71.0, 74.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 103.0, 89.0, 88.0, 82.0, 91.0, 87.0, 94.0, 86.0], "value": 94.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 142.0, 141.0, 140.0, 149.0, 140.0, 137.0, 173.0, 149.0], "value": 144.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 180.0, 166.0, 207.0, 156.0, 235.0, 239.0, 172.0, 177.0], "value": 187.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 69.0, 71.0, 70.0, 68.0, 69.0, 67.0, 69.0, 70.0], "value": 68.8, "unit": "score"}]}]} 19:53:10 INFO - Return code: 0 19:53:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:53:10 ERROR - # TBPL SUCCESS # 19:53:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:53:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:53:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:53:10 INFO - 2016-08-31 19:53:10.367 screenresolution[1669:10361] starting screenresolution argv=screenresolution get 19:53:10 INFO - 2016-08-31 19:53:10.370 screenresolution[1669:10361] Display 0: 1600x1200x32@60 19:53:10 INFO - 2016-08-31 19:53:10.376 screenresolution[1670:10364] starting screenresolution argv=screenresolution list 19:53:10 INFO - Available Modes on Display 0 19:53:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:53:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:53:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:53:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:53:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:53:10 INFO - Intel Iris: 19:53:10 INFO - Chipset Model: Intel Iris 19:53:10 INFO - Type: GPU 19:53:10 INFO - Bus: Built-In 19:53:10 INFO - VRAM (Dynamic, Max): 1536 MB 19:53:10 INFO - Vendor: Intel (0x8086) 19:53:10 INFO - Device ID: 0x0a2e 19:53:10 INFO - Revision ID: 0x0009 19:53:10 INFO - Displays: 19:53:10 INFO - Display: 19:53:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:53:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:53:10 INFO - Display Serial Number: 1600x1200 60 19:53:10 INFO - Main Display: Yes 19:53:10 INFO - Mirror: Off 19:53:10 INFO - Online: Yes 19:53:10 INFO - Rotation: Supported 19:53:10 INFO - Return code: 0 19:53:10 INFO - Running post-action listener: _resource_record_post_action 19:53:10 INFO - [mozharness: 2016-09-01 02:53:10.612386Z] Finished run-tests step (success) 19:53:10 INFO - Running post-run listener: _resource_record_post_run 19:53:10 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 37065728; Write bytes: 706774528; Read time: 240; Write time: 1981 19:53:10 INFO - TinderboxPrint: CPU usage
54.9% 19:53:10 INFO - TinderboxPrint: I/O read bytes / time
37,065,728 / 240 19:53:10 INFO - TinderboxPrint: I/O write bytes / time
706,774,528 / 1,981 19:53:10 INFO - TinderboxPrint: CPU idle
2,181.8 (45.1%) 19:53:10 INFO - TinderboxPrint: CPU system
982.7 (20.3%) 19:53:10 INFO - TinderboxPrint: CPU user
1,673.1 (34.6%) 19:53:10 INFO - TinderboxPrint: Swap in / out
442,306,560 / 0 19:53:10 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 402000384; Write bytes: 390658048; Read time: 29401; Write time: 785 19:53:10 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 29809664; Write bytes: 296282112; Read time: 175; Write time: 1144 19:53:11 INFO - Running post-run listener: _upload_blobber_files 19:53:11 INFO - Blob upload gear active. 19:53:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:53:11 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 19:53:11 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'] 19:53:11 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 19:53:11 INFO - (blobuploader) - INFO - Open directory for files ... 19:53:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:53:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:53:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:53:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:53:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:53:12 INFO - (blobuploader) - INFO - Done attempting. 19:53:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:53:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:53:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:53:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:53:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:53:13 INFO - (blobuploader) - INFO - Done attempting. 19:53:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:53:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:53:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:53:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:53:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:53:17 INFO - (blobuploader) - INFO - Done attempting. 19:53:17 INFO - (blobuploader) - INFO - Iteration through files over. 19:53:17 INFO - Return code: 0 19:53:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:53:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:53:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/beb01863279cf948a691de643e6f4d4c411745b4957af94ae88b126317e91cf7c0252828c4181f2138636fd7aa5e186e1afcad16f1d7194e5763f34f5d6b1161", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fcf88b822c29a231fd43551586fced3c58362143f60b00f73d491db1ed8092077f66987c8a4400b3026e65d3e1a75384774e5757c0a810a705d60816d483235c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/035adfb737666e32e4f5afcfd6d544365f5ffcf755d753035a5d97eb9d33c30519ed01868fb20dded1c804dd820f081f81aa6a1281da4f9bb6d114ef3c4da8ea"} 19:53:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:53:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:53:17 INFO - Contents: 19:53:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/beb01863279cf948a691de643e6f4d4c411745b4957af94ae88b126317e91cf7c0252828c4181f2138636fd7aa5e186e1afcad16f1d7194e5763f34f5d6b1161", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fcf88b822c29a231fd43551586fced3c58362143f60b00f73d491db1ed8092077f66987c8a4400b3026e65d3e1a75384774e5757c0a810a705d60816d483235c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/035adfb737666e32e4f5afcfd6d544365f5ffcf755d753035a5d97eb9d33c30519ed01868fb20dded1c804dd820f081f81aa6a1281da4f9bb6d114ef3c4da8ea"} 19:53:17 INFO - Running post-run listener: copy_logs_to_upload_dir 19:53:17 INFO - Copying logs to upload dir... 19:53:17 INFO - mkdir: /builds/slave/test/build/upload/logs 19:53:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.469250 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-31 19:53:17.553621) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 19:53:17.557069) ========= 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.yKMUC5qelw/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.VJB91QZNkE/Listeners TMPDIR=/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/beb01863279cf948a691de643e6f4d4c411745b4957af94ae88b126317e91cf7c0252828c4181f2138636fd7aa5e186e1afcad16f1d7194e5763f34f5d6b1161", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fcf88b822c29a231fd43551586fced3c58362143f60b00f73d491db1ed8092077f66987c8a4400b3026e65d3e1a75384774e5757c0a810a705d60816d483235c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/035adfb737666e32e4f5afcfd6d544365f5ffcf755d753035a5d97eb9d33c30519ed01868fb20dded1c804dd820f081f81aa6a1281da4f9bb6d114ef3c4da8ea"} build_url:https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011183 build_url: 'https://queue.taskcluster.net/v1/task/Kh4imswHRxW330l7ucfboA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/beb01863279cf948a691de643e6f4d4c411745b4957af94ae88b126317e91cf7c0252828c4181f2138636fd7aa5e186e1afcad16f1d7194e5763f34f5d6b1161", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fcf88b822c29a231fd43551586fced3c58362143f60b00f73d491db1ed8092077f66987c8a4400b3026e65d3e1a75384774e5757c0a810a705d60816d483235c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/035adfb737666e32e4f5afcfd6d544365f5ffcf755d753035a5d97eb9d33c30519ed01868fb20dded1c804dd820f081f81aa6a1281da4f9bb6d114ef3c4da8ea"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 19:53:17.590676) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 19:53:17.591001) ========= 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.yKMUC5qelw/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.VJB91QZNkE/Listeners TMPDIR=/var/folders/t0/q6j3ybpx29q21vrxg9z4zx0800000w/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.005384 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 19:53:17.639361) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 19:53:17.639727) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 19:53:17.640017) ========= ========= Total master_lag: 0.19 =========